{"level":"warn","ts":"2026-03-06T18:32:12.795382Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T18:32:12.79547Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49: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://162.253.55.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49: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-03-06T18:32:12.795541Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T18:32:12.795547Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-03-06T18:32:12.795564Z","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-03-06T18:32:12.796129Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-03-06T18:32:12.796204Z","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://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49: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-03-06T18:32:12.800048Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.612044ms"} {"level":"info","ts":"2026-03-06T18:32:12.805591Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-03-06T18:32:12.805675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-03-06T18:32:12.805703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-03-06T18:32:12.805718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-06T18:32:12.805734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-03-06T18:32:12.805776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-03-06T18:32:12.812298Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-06T18:32:12.813704Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-06T18:32:12.814709Z","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-03-06T18:32:12.816873Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-06T18:32:12.822292Z","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-03-06T18:32:12.822472Z","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-03-06T18:32:12.822499Z","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-03-06T18:32:12.822417Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-06T18:32:12.823731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-03-06T18:32:12.824016Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-03-06T18:32:12.824219Z","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-03-06T18:32:12.824404Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-03-06T18:32:12.824454Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-03-06T18:32:12.824577Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-06T18:32:12.824619Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-06T18:32:13.506622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-03-06T18:32:13.506686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-03-06T18:32:13.506711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-03-06T18:32:13.506734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-03-06T18:32:13.506746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-03-06T18:32:13.506763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-03-06T18:32:13.506776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-03-06T18:32:13.507932Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-03-06T18:32:13.507979Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T18:32:13.508571Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T18:32:13.509327Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T18:32:13.510395Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T18:32:13.510558Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T18:32:13.510596Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T18:32:13.511524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-06T18:32:13.512481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-03-06T18:32:13.515269Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-06T18:32:13.515401Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-03-06T18:32:19.8957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.987165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-06T18:32:19.895783Z","caller":"traceutil/trace.go:171","msg":"trace[943467424] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:315; }","duration":"202.147938ms","start":"2026-03-06T18:32:19.693619Z","end":"2026-03-06T18:32:19.895767Z","steps":["trace[943467424] 'range keys from in-memory index tree' (duration: 201.818992ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:32:30.291273Z","caller":"traceutil/trace.go:171","msg":"trace[1233354114] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"161.28258ms","start":"2026-03-06T18:32:30.129965Z","end":"2026-03-06T18:32:30.291247Z","steps":["trace[1233354114] 'process raft request' (duration: 161.129167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:31.005063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.450216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-06T18:32:31.005122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.57613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:32:31.005178Z","caller":"traceutil/trace.go:171","msg":"trace[1207889959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:448; }","duration":"440.60944ms","start":"2026-03-06T18:32:30.564542Z","end":"2026-03-06T18:32:31.005151Z","steps":["trace[1207889959] 'range keys from in-memory index tree' (duration: 440.336124ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:32:31.005215Z","caller":"traceutil/trace.go:171","msg":"trace[643037123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"575.735464ms","start":"2026-03-06T18:32:30.429457Z","end":"2026-03-06T18:32:31.005192Z","steps":["trace[643037123] 'range keys from in-memory index tree' (duration: 575.448397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:31.005227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:30.564524Z","time spent":"440.692331ms","remote":"127.0.0.1:50886","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-03-06T18:32:31.005271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:30.429421Z","time spent":"575.841406ms","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T18:32:31.323401Z","caller":"traceutil/trace.go:171","msg":"trace[2099420045] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"172.346428ms","start":"2026-03-06T18:32:31.151018Z","end":"2026-03-06T18:32:31.323365Z","steps":["trace[2099420045] 'read index received' (duration: 172.02843ms)","trace[2099420045] 'applied index is now lower than readState.Index' (duration: 316.757µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:32:31.323473Z","caller":"traceutil/trace.go:171","msg":"trace[776588227] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"313.753837ms","start":"2026-03-06T18:32:31.009682Z","end":"2026-03-06T18:32:31.323436Z","steps":["trace[776588227] 'process raft request' (duration: 313.317737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:31.323545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.517971ms","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-03-06T18:32:31.323605Z","caller":"traceutil/trace.go:171","msg":"trace[623320246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:449; }","duration":"172.632064ms","start":"2026-03-06T18:32:31.150959Z","end":"2026-03-06T18:32:31.323591Z","steps":["trace[623320246] 'agreement among raft nodes before linearized reading' (duration: 172.515251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:31.324193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:31.009665Z","time spent":"313.880151ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:32:31.714508Z","caller":"traceutil/trace.go:171","msg":"trace[2045658012] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"259.956669ms","start":"2026-03-06T18:32:31.454524Z","end":"2026-03-06T18:32:31.714481Z","steps":["trace[2045658012] 'read index received' (duration: 254.752958ms)","trace[2045658012] 'applied index is now lower than readState.Index' (duration: 5.202751ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:32:31.714644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.094392ms","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-03-06T18:32:31.714682Z","caller":"traceutil/trace.go:171","msg":"trace[209999897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:450; }","duration":"260.190044ms","start":"2026-03-06T18:32:31.454481Z","end":"2026-03-06T18:32:31.714672Z","steps":["trace[209999897] 'agreement among raft nodes before linearized reading' (duration: 260.098322ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:32:31.71475Z","caller":"traceutil/trace.go:171","msg":"trace[268653216] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"386.411552ms","start":"2026-03-06T18:32:31.328313Z","end":"2026-03-06T18:32:31.714725Z","steps":["trace[268653216] 'process raft request' (duration: 381.053458ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:31.714898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:31.328291Z","time spent":"386.526384ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:32:40.863851Z","caller":"traceutil/trace.go:171","msg":"trace[1415453423] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"420.587345ms","start":"2026-03-06T18:32:40.443233Z","end":"2026-03-06T18:32:40.863821Z","steps":["trace[1415453423] 'process raft request' (duration: 420.437652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:40.864023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:40.443202Z","time spent":"420.731219ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:32:44.335954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.10661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:32:44.336141Z","caller":"traceutil/trace.go:171","msg":"trace[1463450193] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"545.519342ms","start":"2026-03-06T18:32:43.790593Z","end":"2026-03-06T18:32:44.336112Z","steps":["trace[1463450193] 'process raft request' (duration: 144.87475ms)","trace[1463450193] 'compare' (duration: 399.898315ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:32:44.336274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:43.790573Z","time spent":"545.660586ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:32:51.563711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763152305457545,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T18:32:51.725141Z","caller":"traceutil/trace.go:171","msg":"trace[987337870] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"820.263114ms","start":"2026-03-06T18:32:50.904846Z","end":"2026-03-06T18:32:51.725109Z","steps":["trace[987337870] 'process raft request' (duration: 820.1045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:51.725315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:50.904819Z","time spent":"820.405318ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:32:51.770752Z","caller":"traceutil/trace.go:171","msg":"trace[327192800] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:548; }","duration":"707.475309ms","start":"2026-03-06T18:32:51.063251Z","end":"2026-03-06T18:32:51.770726Z","steps":["trace[327192800] 'read index received' (duration: 661.774249ms)","trace[327192800] 'applied index is now lower than readState.Index' (duration: 45.69927ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:32:51.771015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.095711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T18:32:51.771056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.798699ms","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-03-06T18:32:51.771117Z","caller":"traceutil/trace.go:171","msg":"trace[1746710730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"344.197243ms","start":"2026-03-06T18:32:51.426863Z","end":"2026-03-06T18:32:51.77106Z","steps":["trace[1746710730] 'agreement among raft nodes before linearized reading' (duration: 344.05764ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:32:51.771136Z","caller":"traceutil/trace.go:171","msg":"trace[661538388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"332.912432ms","start":"2026-03-06T18:32:51.438203Z","end":"2026-03-06T18:32:51.771115Z","steps":["trace[661538388] 'agreement among raft nodes before linearized reading' (duration: 332.743338ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:51.77118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:51.438193Z","time spent":"332.978844ms","remote":"127.0.0.1:50886","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-03-06T18:32:51.771207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:51.426839Z","time spent":"344.319606ms","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:32:51.7713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.015621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wcdxg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-06T18:32:51.771399Z","caller":"traceutil/trace.go:171","msg":"trace[708157978] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wcdxg; range_end:; response_count:1; response_revision:533; }","duration":"708.179175ms","start":"2026-03-06T18:32:51.063193Z","end":"2026-03-06T18:32:51.771372Z","steps":["trace[708157978] 'agreement among raft nodes before linearized reading' (duration: 707.617952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:51.771453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:51.063168Z","time spent":"708.272977ms","remote":"127.0.0.1:50800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-wcdxg\" "} {"level":"warn","ts":"2026-03-06T18:32:52.319148Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763152305457550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T18:32:52.92513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.841731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-03-06T18:32:52.925397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106633657s","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-03-06T18:32:52.925515Z","caller":"traceutil/trace.go:171","msg":"trace[314444989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"1.106794951s","start":"2026-03-06T18:32:51.818706Z","end":"2026-03-06T18:32:52.925501Z","steps":["trace[314444989] 'agreement among raft nodes before linearized reading' (duration: 1.106603327s)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:32:52.925243Z","caller":"traceutil/trace.go:171","msg":"trace[1777226981] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"1.106484934s","start":"2026-03-06T18:32:51.818741Z","end":"2026-03-06T18:32:52.925226Z","steps":["trace[1777226981] 'read index received' (duration: 879.346916ms)","trace[1777226981] 'applied index is now lower than readState.Index' (duration: 227.136598ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:32:52.925596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:51.81869Z","time spent":"1.106894403s","remote":"127.0.0.1:50886","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-03-06T18:32:52.92569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.615708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:32:52.925728Z","caller":"traceutil/trace.go:171","msg":"trace[2111734669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"496.68917ms","start":"2026-03-06T18:32:52.429028Z","end":"2026-03-06T18:32:52.925717Z","steps":["trace[2111734669] 'agreement among raft nodes before linearized reading' (duration: 496.623909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:52.92577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:52.429008Z","time spent":"496.754141ms","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:32:52.926049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.972394ms","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-03-06T18:32:52.926133Z","caller":"traceutil/trace.go:171","msg":"trace[1237233838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"468.091727ms","start":"2026-03-06T18:32:52.458019Z","end":"2026-03-06T18:32:52.92611Z","steps":["trace[1237233838] 'agreement among raft nodes before linearized reading' (duration: 467.856452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:52.926177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:52.458005Z","time spent":"468.163838ms","remote":"127.0.0.1:50886","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-03-06T18:32:52.926484Z","caller":"traceutil/trace.go:171","msg":"trace[2105774877] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.141745851s","start":"2026-03-06T18:32:51.783513Z","end":"2026-03-06T18:32:52.925259Z","steps":["trace[2105774877] 'process raft request' (duration: 914.679635ms)","trace[2105774877] 'compare' (duration: 226.277228ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:32:52.926702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:51.783489Z","time spent":"1.143107423s","remote":"127.0.0.1:50800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:32:53.560984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.561628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:32:53.561143Z","caller":"traceutil/trace.go:171","msg":"trace[203552336] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"627.032743ms","start":"2026-03-06T18:32:52.934086Z","end":"2026-03-06T18:32:53.561119Z","steps":["trace[203552336] 'process raft request' (duration: 518.215359ms)","trace[203552336] 'compare' (duration: 108.333703ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:32:53.561341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:52.934024Z","time spent":"627.235758ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:32:54.18574Z","caller":"traceutil/trace.go:171","msg":"trace[1317652265] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"109.374066ms","start":"2026-03-06T18:32:54.076319Z","end":"2026-03-06T18:32:54.185694Z","steps":["trace[1317652265] 'process raft request' (duration: 109.190782ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:32:54.334036Z","caller":"traceutil/trace.go:171","msg":"trace[1830829068] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"244.285065ms","start":"2026-03-06T18:32:54.089725Z","end":"2026-03-06T18:32:54.33401Z","steps":["trace[1830829068] 'process raft request' (duration: 244.149112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:54.696582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.486079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:32:54.696681Z","caller":"traceutil/trace.go:171","msg":"trace[127559055] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"263.614534ms","start":"2026-03-06T18:32:54.433051Z","end":"2026-03-06T18:32:54.696666Z","steps":["trace[127559055] 'process raft request' (duration: 103.977422ms)","trace[127559055] 'compare' (duration: 159.377526ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:32:58.503652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.886777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-03-06T18:32:58.503752Z","caller":"traceutil/trace.go:171","msg":"trace[1426463670] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:605; }","duration":"194.066441ms","start":"2026-03-06T18:32:58.309663Z","end":"2026-03-06T18:32:58.50373Z","steps":["trace[1426463670] 'range keys from in-memory index tree' (duration: 193.619211ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:32:59.857324Z","caller":"traceutil/trace.go:171","msg":"trace[483665928] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"321.327803ms","start":"2026-03-06T18:32:59.535971Z","end":"2026-03-06T18:32:59.857298Z","steps":["trace[483665928] 'process raft request' (duration: 320.835772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:59.857481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:59.535951Z","time spent":"321.449775ms","remote":"127.0.0.1:50650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:32:59.898248Z","caller":"traceutil/trace.go:171","msg":"trace[2085985237] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"358.675129ms","start":"2026-03-06T18:32:59.539537Z","end":"2026-03-06T18:32:59.898212Z","steps":["trace[2085985237] 'process raft request' (duration: 358.341401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:59.898408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:59.539493Z","time spent":"358.844173ms","remote":"127.0.0.1:50650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T18:32:59.898541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.820489ms","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-03-06T18:32:59.898556Z","caller":"traceutil/trace.go:171","msg":"trace[416509234] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"313.44925ms","start":"2026-03-06T18:32:59.584694Z","end":"2026-03-06T18:32:59.898144Z","steps":["trace[416509234] 'read index received' (duration: 272.106991ms)","trace[416509234] 'applied index is now lower than readState.Index' (duration: 41.340849ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:32:59.89865Z","caller":"traceutil/trace.go:171","msg":"trace[131225051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"313.980733ms","start":"2026-03-06T18:32:59.584643Z","end":"2026-03-06T18:32:59.898624Z","steps":["trace[131225051] 'agreement among raft nodes before linearized reading' (duration: 313.635365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:32:59.898753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:32:59.584625Z","time spent":"314.108235ms","remote":"127.0.0.1:50886","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-03-06T18:32:59.899022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.218878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-03-06T18:32:59.899065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.576948ms","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-03-06T18:32:59.899087Z","caller":"traceutil/trace.go:171","msg":"trace[750383981] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:618; }","duration":"192.318891ms","start":"2026-03-06T18:32:59.706745Z","end":"2026-03-06T18:32:59.899064Z","steps":["trace[750383981] 'agreement among raft nodes before linearized reading' (duration: 192.058465ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:32:59.899111Z","caller":"traceutil/trace.go:171","msg":"trace[819653471] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:618; }","duration":"130.678161ms","start":"2026-03-06T18:32:59.768421Z","end":"2026-03-06T18:32:59.899099Z","steps":["trace[819653471] 'agreement among raft nodes before linearized reading' (duration: 130.485766ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:04.936102Z","caller":"traceutil/trace.go:171","msg":"trace[1300755284] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"157.41295ms","start":"2026-03-06T18:33:04.778664Z","end":"2026-03-06T18:33:04.936077Z","steps":["trace[1300755284] 'process raft request' (duration: 157.241946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:06.626002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.366198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:33:06.626135Z","caller":"traceutil/trace.go:171","msg":"trace[1023543134] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"268.347824ms","start":"2026-03-06T18:33:06.357768Z","end":"2026-03-06T18:33:06.626115Z","steps":["trace[1023543134] 'read index received' (duration: 107.687229ms)","trace[1023543134] 'applied index is now lower than readState.Index' (duration: 160.659115ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:33:06.626255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.541856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:06.626292Z","caller":"traceutil/trace.go:171","msg":"trace[220400705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"184.596708ms","start":"2026-03-06T18:33:06.441683Z","end":"2026-03-06T18:33:06.62628Z","steps":["trace[220400705] 'agreement among raft nodes before linearized reading' (duration: 184.542046ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:06.626306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.546588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-06T18:33:06.626361Z","caller":"traceutil/trace.go:171","msg":"trace[1551158397] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:694; }","duration":"268.63899ms","start":"2026-03-06T18:33:06.357713Z","end":"2026-03-06T18:33:06.626352Z","steps":["trace[1551158397] 'agreement among raft nodes before linearized reading' (duration: 268.502207ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:06.626309Z","caller":"traceutil/trace.go:171","msg":"trace[770628066] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"375.292196ms","start":"2026-03-06T18:33:06.250976Z","end":"2026-03-06T18:33:06.626268Z","steps":["trace[770628066] 'process raft request' (duration: 214.516219ms)","trace[770628066] 'compare' (duration: 160.214744ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:33:06.626526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:06.250959Z","time spent":"375.47614ms","remote":"127.0.0.1:51062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:33:06.836506Z","caller":"traceutil/trace.go:171","msg":"trace[1839663322] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"188.15829ms","start":"2026-03-06T18:33:06.648321Z","end":"2026-03-06T18:33:06.83648Z","steps":["trace[1839663322] 'process raft request' (duration: 187.913924ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:06.836794Z","caller":"traceutil/trace.go:171","msg":"trace[1075108655] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"172.069358ms","start":"2026-03-06T18:33:06.664714Z","end":"2026-03-06T18:33:06.836783Z","steps":["trace[1075108655] 'process raft request' (duration: 171.991126ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:08.152493Z","caller":"traceutil/trace.go:171","msg":"trace[1814819617] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"138.88773ms","start":"2026-03-06T18:33:08.013583Z","end":"2026-03-06T18:33:08.15247Z","steps":["trace[1814819617] 'process raft request' (duration: 138.543882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:13.764642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.780521ms","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-03-06T18:33:13.764734Z","caller":"traceutil/trace.go:171","msg":"trace[463693898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:723; }","duration":"572.918575ms","start":"2026-03-06T18:33:13.191791Z","end":"2026-03-06T18:33:13.76471Z","steps":["trace[463693898] 'range keys from in-memory index tree' (duration: 572.619588ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:13.764789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:13.191769Z","time spent":"573.011016ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T18:33:13.764866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.335438042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:13.764916Z","caller":"traceutil/trace.go:171","msg":"trace[436390957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"1.335518664s","start":"2026-03-06T18:33:12.429383Z","end":"2026-03-06T18:33:13.764901Z","steps":["trace[436390957] 'range keys from in-memory index tree' (duration: 1.335371881s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:13.764949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:12.429363Z","time spent":"1.335577766s","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T18:33:14.248876Z","caller":"traceutil/trace.go:171","msg":"trace[1028359630] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"474.819486ms","start":"2026-03-06T18:33:13.774036Z","end":"2026-03-06T18:33:14.248855Z","steps":["trace[1028359630] 'read index received' (duration: 474.629812ms)","trace[1028359630] 'applied index is now lower than readState.Index' (duration: 188.784µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:33:14.249175Z","caller":"traceutil/trace.go:171","msg":"trace[2015397614] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"480.065029ms","start":"2026-03-06T18:33:13.769098Z","end":"2026-03-06T18:33:14.249163Z","steps":["trace[2015397614] 'process raft request' (duration: 479.633469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:14.249285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:13.769064Z","time spent":"480.14491ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:33:14.249436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.386176ms","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-03-06T18:33:14.249507Z","caller":"traceutil/trace.go:171","msg":"trace[1843977192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:724; }","duration":"108.490998ms","start":"2026-03-06T18:33:14.140996Z","end":"2026-03-06T18:33:14.249487Z","steps":["trace[1843977192] 'agreement among raft nodes before linearized reading' (duration: 108.292683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:14.249597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.523874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:14.250014Z","caller":"traceutil/trace.go:171","msg":"trace[602565922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"475.963504ms","start":"2026-03-06T18:33:13.774027Z","end":"2026-03-06T18:33:14.24999Z","steps":["trace[602565922] 'agreement among raft nodes before linearized reading' (duration: 475.478152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:14.250069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:13.773999Z","time spent":"476.054785ms","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:33:14.810581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.889302ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:14.810724Z","caller":"traceutil/trace.go:171","msg":"trace[1618878861] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:724; }","duration":"178.046706ms","start":"2026-03-06T18:33:14.632654Z","end":"2026-03-06T18:33:14.810701Z","steps":["trace[1618878861] 'range keys from in-memory index tree' (duration: 177.871012ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:14.811072Z","caller":"traceutil/trace.go:171","msg":"trace[234636063] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"556.628268ms","start":"2026-03-06T18:33:14.254416Z","end":"2026-03-06T18:33:14.811044Z","steps":["trace[234636063] 'process raft request' (duration: 555.972443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:14.811297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:14.254388Z","time spent":"556.766981ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:33:15.141675Z","caller":"traceutil/trace.go:171","msg":"trace[1394664458] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"887.014855ms","start":"2026-03-06T18:33:14.254621Z","end":"2026-03-06T18:33:15.141636Z","steps":["trace[1394664458] 'process raft request' (duration: 832.776741ms)","trace[1394664458] 'compare' (duration: 53.866466ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:33:15.141851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:14.254613Z","time spent":"887.147868ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:33:15.142009Z","caller":"traceutil/trace.go:171","msg":"trace[1005350988] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"887.288052ms","start":"2026-03-06T18:33:14.254694Z","end":"2026-03-06T18:33:15.141982Z","steps":["trace[1005350988] 'process raft request' (duration: 886.861142ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:15.142148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:14.254672Z","time spent":"887.399934ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:33:15.142448Z","caller":"traceutil/trace.go:171","msg":"trace[992292093] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"884.671401ms","start":"2026-03-06T18:33:14.257747Z","end":"2026-03-06T18:33:15.142419Z","steps":["trace[992292093] 'process raft request' (duration: 883.980435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:15.142512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:14.25773Z","time spent":"884.751444ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:33:16.6591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.5489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:16.659191Z","caller":"traceutil/trace.go:171","msg":"trace[1937803472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:729; }","duration":"230.689553ms","start":"2026-03-06T18:33:16.428477Z","end":"2026-03-06T18:33:16.659166Z","steps":["trace[1937803472] 'range keys from in-memory index tree' (duration: 230.448378ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:16.659344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.743203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-06T18:33:16.659402Z","caller":"traceutil/trace.go:171","msg":"trace[210121923] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:729; }","duration":"300.843065ms","start":"2026-03-06T18:33:16.358541Z","end":"2026-03-06T18:33:16.659384Z","steps":["trace[210121923] 'range keys from in-memory index tree' (duration: 300.596619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:16.659429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:16.358523Z","time spent":"300.900616ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.49\" "} {"level":"warn","ts":"2026-03-06T18:33:16.659437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.596544ms","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-03-06T18:33:16.659514Z","caller":"traceutil/trace.go:171","msg":"trace[989986745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:729; }","duration":"404.739867ms","start":"2026-03-06T18:33:16.254755Z","end":"2026-03-06T18:33:16.659495Z","steps":["trace[989986745] 'range keys from in-memory index tree' (duration: 404.383448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:16.659591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:16.254722Z","time spent":"404.858619ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T18:33:17.160937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763152305457919,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T18:33:17.659161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:16.660471Z","time spent":"998.684387ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-06T18:33:18.31869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.113155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:33:18.318805Z","caller":"traceutil/trace.go:171","msg":"trace[1908759276] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:752; }","duration":"1.658163084s","start":"2026-03-06T18:33:16.660624Z","end":"2026-03-06T18:33:18.318787Z","steps":["trace[1908759276] 'read index received' (duration: 998.297579ms)","trace[1908759276] 'applied index is now lower than readState.Index' (duration: 659.863895ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:33:18.31891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658284866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:18.318856Z","caller":"traceutil/trace.go:171","msg":"trace[111234975] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"1.655322678s","start":"2026-03-06T18:33:16.663494Z","end":"2026-03-06T18:33:18.318816Z","steps":["trace[111234975] 'process raft request' (duration: 1.551970109s)","trace[111234975] 'compare' (duration: 102.269424ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:33:18.318946Z","caller":"traceutil/trace.go:171","msg":"trace[645645757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"1.658353098s","start":"2026-03-06T18:33:16.66058Z","end":"2026-03-06T18:33:18.318933Z","steps":["trace[645645757] 'agreement among raft nodes before linearized reading' (duration: 1.658277716s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:18.318976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:16.660558Z","time spent":"1.658412089s","remote":"127.0.0.1:50626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:33:18.319107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:16.663477Z","time spent":"1.655507742s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:33:18.382989Z","caller":"traceutil/trace.go:171","msg":"trace[1606009099] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"722.077653ms","start":"2026-03-06T18:33:17.660879Z","end":"2026-03-06T18:33:18.382957Z","steps":["trace[1606009099] 'process raft request' (duration: 721.832337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:18.383101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.567546549s","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-03-06T18:33:18.38314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236201859s","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-03-06T18:33:18.383152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:17.660854Z","time spent":"722.204066ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:33:18.383165Z","caller":"traceutil/trace.go:171","msg":"trace[1407160092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:731; }","duration":"1.567637891s","start":"2026-03-06T18:33:16.815511Z","end":"2026-03-06T18:33:18.383148Z","steps":["trace[1407160092] 'agreement among raft nodes before linearized reading' (duration: 1.567416635s)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:18.383193Z","caller":"traceutil/trace.go:171","msg":"trace[1624676980] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:731; }","duration":"1.23627662s","start":"2026-03-06T18:33:17.146903Z","end":"2026-03-06T18:33:18.38318Z","steps":["trace[1624676980] 'agreement among raft nodes before linearized reading' (duration: 1.236072626s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:18.383212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:16.815495Z","time spent":"1.567709312s","remote":"127.0.0.1:50886","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-03-06T18:33:18.383231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:17.146897Z","time spent":"1.236325871s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T18:33:18.383653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237498778s","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-03-06T18:33:18.383693Z","caller":"traceutil/trace.go:171","msg":"trace[759181055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:731; }","duration":"1.23757486s","start":"2026-03-06T18:33:17.146107Z","end":"2026-03-06T18:33:18.383682Z","steps":["trace[759181055] 'agreement among raft nodes before linearized reading' (duration: 1.237291773s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:18.383717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:17.14608Z","time spent":"1.237632482s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T18:33:18.383752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236422224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T18:33:18.383831Z","caller":"traceutil/trace.go:171","msg":"trace[1616849058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:731; }","duration":"1.236583178s","start":"2026-03-06T18:33:17.147226Z","end":"2026-03-06T18:33:18.383809Z","steps":["trace[1616849058] 'agreement among raft nodes before linearized reading' (duration: 1.235672907s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:18.38389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:17.147192Z","time spent":"1.236688569s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-06T18:33:18.524653Z","caller":"traceutil/trace.go:171","msg":"trace[1321366428] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"136.325472ms","start":"2026-03-06T18:33:18.388298Z","end":"2026-03-06T18:33:18.524624Z","steps":["trace[1321366428] 'process raft request' (duration: 136.269131ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:18.524688Z","caller":"traceutil/trace.go:171","msg":"trace[573512517] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"136.755391ms","start":"2026-03-06T18:33:18.387894Z","end":"2026-03-06T18:33:18.524649Z","steps":["trace[573512517] 'process raft request' (duration: 127.707052ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:18.524719Z","caller":"traceutil/trace.go:171","msg":"trace[518246415] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"136.771481ms","start":"2026-03-06T18:33:18.387914Z","end":"2026-03-06T18:33:18.524685Z","steps":["trace[518246415] 'process raft request' (duration: 136.621968ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:18.525754Z","caller":"traceutil/trace.go:171","msg":"trace[1894699266] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"137.337475ms","start":"2026-03-06T18:33:18.388395Z","end":"2026-03-06T18:33:18.525732Z","steps":["trace[1894699266] 'process raft request' (duration: 136.198958ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:19.1575Z","caller":"traceutil/trace.go:171","msg":"trace[283059153] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"149.958387ms","start":"2026-03-06T18:33:19.007506Z","end":"2026-03-06T18:33:19.157465Z","steps":["trace[283059153] 'read index received' (duration: 149.691301ms)","trace[283059153] 'applied index is now lower than readState.Index' (duration: 265.556µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:33:19.157592Z","caller":"traceutil/trace.go:171","msg":"trace[722641834] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"153.154711ms","start":"2026-03-06T18:33:19.004402Z","end":"2026-03-06T18:33:19.157557Z","steps":["trace[722641834] 'process raft request' (duration: 152.843214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:19.157754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.195162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:19.157977Z","caller":"traceutil/trace.go:171","msg":"trace[1543848221] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:736; }","duration":"150.501269ms","start":"2026-03-06T18:33:19.007457Z","end":"2026-03-06T18:33:19.157958Z","steps":["trace[1543848221] 'agreement among raft nodes before linearized reading' (duration: 150.196492ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:19.158625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.594452ms","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-03-06T18:33:19.1587Z","caller":"traceutil/trace.go:171","msg":"trace[1065871512] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:736; }","duration":"102.745495ms","start":"2026-03-06T18:33:19.05594Z","end":"2026-03-06T18:33:19.158685Z","steps":["trace[1065871512] 'agreement among raft nodes before linearized reading' (duration: 101.972287ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:19.477104Z","caller":"traceutil/trace.go:171","msg":"trace[455265446] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"188.13754ms","start":"2026-03-06T18:33:19.288934Z","end":"2026-03-06T18:33:19.477072Z","steps":["trace[455265446] 'process raft request' (duration: 187.978366ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:38.667948Z","caller":"traceutil/trace.go:171","msg":"trace[772740764] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"149.987947ms","start":"2026-03-06T18:33:38.517928Z","end":"2026-03-06T18:33:38.667916Z","steps":["trace[772740764] 'read index received' (duration: 149.70014ms)","trace[772740764] 'applied index is now lower than readState.Index' (duration: 286.147µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:33:38.668012Z","caller":"traceutil/trace.go:171","msg":"trace[707184914] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"199.701207ms","start":"2026-03-06T18:33:38.468276Z","end":"2026-03-06T18:33:38.667977Z","steps":["trace[707184914] 'process raft request' (duration: 199.37648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:38.668439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.210553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:38.668518Z","caller":"traceutil/trace.go:171","msg":"trace[2042118813] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:794; }","duration":"150.628722ms","start":"2026-03-06T18:33:38.51787Z","end":"2026-03-06T18:33:38.668499Z","steps":["trace[2042118813] 'agreement among raft nodes before linearized reading' (duration: 150.222753ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:39.183412Z","caller":"traceutil/trace.go:171","msg":"trace[660755973] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"510.159054ms","start":"2026-03-06T18:33:38.673211Z","end":"2026-03-06T18:33:39.18337Z","steps":["trace[660755973] 'process raft request' (duration: 456.495974ms)","trace[660755973] 'compare' (duration: 53.497626ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:33:39.183634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:38.673181Z","time spent":"510.338768ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:33:39.77388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.150014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:33:39.774044Z","caller":"traceutil/trace.go:171","msg":"trace[216376740] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:824; }","duration":"980.452346ms","start":"2026-03-06T18:33:38.79357Z","end":"2026-03-06T18:33:39.774022Z","steps":["trace[216376740] 'read index received' (duration: 336.163991ms)","trace[216376740] 'applied index is now lower than readState.Index' (duration: 644.286455ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:33:39.774161Z","caller":"traceutil/trace.go:171","msg":"trace[1618617838] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"1.100319407s","start":"2026-03-06T18:33:38.673827Z","end":"2026-03-06T18:33:39.774147Z","steps":["trace[1618617838] 'process raft request' (duration: 887.769813ms)","trace[1618617838] 'compare' (duration: 211.653043ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:33:39.774259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:38.673806Z","time spent":"1.100391109s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:33:39.774539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.423956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:39.774649Z","caller":"traceutil/trace.go:171","msg":"trace[88803516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"342.642481ms","start":"2026-03-06T18:33:39.431984Z","end":"2026-03-06T18:33:39.774626Z","steps":["trace[88803516] 'agreement among raft nodes before linearized reading' (duration: 342.469267ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:39.774643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.05622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-06T18:33:39.774628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.958477ms","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-03-06T18:33:39.774713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:39.431913Z","time spent":"342.787455ms","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T18:33:39.774749Z","caller":"traceutil/trace.go:171","msg":"trace[1574264958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"981.235194ms","start":"2026-03-06T18:33:38.793486Z","end":"2026-03-06T18:33:39.774721Z","steps":["trace[1574264958] 'agreement among raft nodes before linearized reading' (duration: 980.928747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:39.774812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:38.793452Z","time spent":"981.349877ms","remote":"127.0.0.1:50886","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-03-06T18:33:39.774762Z","caller":"traceutil/trace.go:171","msg":"trace[1366096754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"981.192433ms","start":"2026-03-06T18:33:38.793544Z","end":"2026-03-06T18:33:39.774736Z","steps":["trace[1366096754] 'agreement among raft nodes before linearized reading' (duration: 980.702632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:39.775131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:38.793508Z","time spent":"981.607403ms","remote":"127.0.0.1:50886","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":"info","ts":"2026-03-06T18:33:40.088356Z","caller":"traceutil/trace.go:171","msg":"trace[634170583] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"307.589041ms","start":"2026-03-06T18:33:39.780738Z","end":"2026-03-06T18:33:40.088327Z","steps":["trace[634170583] 'process raft request' (duration: 307.410117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:40.088536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:39.780706Z","time spent":"307.731635ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:33:40.095404Z","caller":"traceutil/trace.go:171","msg":"trace[335585646] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"178.538347ms","start":"2026-03-06T18:33:39.91684Z","end":"2026-03-06T18:33:40.095378Z","steps":["trace[335585646] 'process raft request' (duration: 178.414524ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:41.536372Z","caller":"traceutil/trace.go:171","msg":"trace[377540439] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"106.267077ms","start":"2026-03-06T18:33:41.430084Z","end":"2026-03-06T18:33:41.536351Z","steps":["trace[377540439] 'read index received' (duration: 106.126863ms)","trace[377540439] 'applied index is now lower than readState.Index' (duration: 138.653µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:33:41.536547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.397779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:41.536584Z","caller":"traceutil/trace.go:171","msg":"trace[595155367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"106.558514ms","start":"2026-03-06T18:33:41.430015Z","end":"2026-03-06T18:33:41.536573Z","steps":["trace[595155367] 'agreement among raft nodes before linearized reading' (duration: 106.431381ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:41.536687Z","caller":"traceutil/trace.go:171","msg":"trace[1622898188] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"338.672399ms","start":"2026-03-06T18:33:41.197978Z","end":"2026-03-06T18:33:41.53665Z","steps":["trace[1622898188] 'process raft request' (duration: 338.199609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:41.536875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:41.197948Z","time spent":"338.826413ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:33:43.295876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720238748s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T18:33:43.296005Z","caller":"traceutil/trace.go:171","msg":"trace[112383925] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"1.75796599s","start":"2026-03-06T18:33:41.538017Z","end":"2026-03-06T18:33:43.295983Z","steps":["trace[112383925] 'read index received' (duration: 37.461626ms)","trace[112383925] 'applied index is now lower than readState.Index' (duration: 1.720502894s)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:33:43.296358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.480988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T18:33:43.29641Z","caller":"traceutil/trace.go:171","msg":"trace[416876172] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:801; }","duration":"259.54676ms","start":"2026-03-06T18:33:43.036847Z","end":"2026-03-06T18:33:43.296394Z","steps":["trace[416876172] 'agreement among raft nodes before linearized reading' (duration: 259.443237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:43.296406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.938172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T18:33:43.296495Z","caller":"traceutil/trace.go:171","msg":"trace[1000328952] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:801; }","duration":"755.086466ms","start":"2026-03-06T18:33:42.54139Z","end":"2026-03-06T18:33:43.296477Z","steps":["trace[1000328952] 'agreement among raft nodes before linearized reading' (duration: 754.879161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:43.29654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:42.541367Z","time spent":"755.162648ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":100,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-03-06T18:33:43.296593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.921316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T18:33:43.296651Z","caller":"traceutil/trace.go:171","msg":"trace[256080546] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:801; }","duration":"843.012768ms","start":"2026-03-06T18:33:42.453613Z","end":"2026-03-06T18:33:43.296626Z","steps":["trace[256080546] 'agreement among raft nodes before linearized reading' (duration: 842.853064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:43.296654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.164989ms","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-03-06T18:33:43.296726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758634365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:43.29677Z","caller":"traceutil/trace.go:171","msg":"trace[493482673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"1.758789279s","start":"2026-03-06T18:33:41.537969Z","end":"2026-03-06T18:33:43.296759Z","steps":["trace[493482673] 'agreement among raft nodes before linearized reading' (duration: 1.758653766s)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:43.29678Z","caller":"traceutil/trace.go:171","msg":"trace[1694506095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:801; }","duration":"592.318603ms","start":"2026-03-06T18:33:42.704433Z","end":"2026-03-06T18:33:43.296752Z","steps":["trace[1694506095] 'agreement among raft nodes before linearized reading' (duration: 591.935164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:43.296744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495207836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-06T18:33:43.296804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:41.53795Z","time spent":"1.75884549s","remote":"127.0.0.1:50626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:33:43.296846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:42.704349Z","time spent":"592.487407ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T18:33:43.29687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.517275565s","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-03-06T18:33:43.296904Z","caller":"traceutil/trace.go:171","msg":"trace[1071303009] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:801; }","duration":"1.517344677s","start":"2026-03-06T18:33:41.779549Z","end":"2026-03-06T18:33:43.296893Z","steps":["trace[1071303009] 'agreement among raft nodes before linearized reading' (duration: 1.517188464s)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:43.296883Z","caller":"traceutil/trace.go:171","msg":"trace[1683867023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"1.495355559s","start":"2026-03-06T18:33:41.801482Z","end":"2026-03-06T18:33:43.296838Z","steps":["trace[1683867023] 'agreement among raft nodes before linearized reading' (duration: 1.495001101s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:43.296937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:41.779528Z","time spent":"1.517403449s","remote":"127.0.0.1:50886","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-03-06T18:33:43.296959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:41.801466Z","time spent":"1.495481122s","remote":"127.0.0.1:50886","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-03-06T18:33:43.297009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203213256s","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-03-06T18:33:43.296705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:42.453586Z","time spent":"843.107771ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":100,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-03-06T18:33:43.296402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.563932365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T18:33:43.297055Z","caller":"traceutil/trace.go:171","msg":"trace[1926945010] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"1.203309958s","start":"2026-03-06T18:33:42.093733Z","end":"2026-03-06T18:33:43.297043Z","steps":["trace[1926945010] 'agreement among raft nodes before linearized reading' (duration: 1.203137904s)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:43.297087Z","caller":"traceutil/trace.go:171","msg":"trace[1316985483] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:801; }","duration":"1.564717792s","start":"2026-03-06T18:33:41.73236Z","end":"2026-03-06T18:33:43.297077Z","steps":["trace[1316985483] 'agreement among raft nodes before linearized reading' (duration: 1.563903394s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:43.297091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:42.093708Z","time spent":"1.20337583s","remote":"127.0.0.1:50886","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-03-06T18:33:43.297121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:41.732331Z","time spent":"1.564781624s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-03-06T18:33:43.807205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763152305458132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T18:33:44.307834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763152305458132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T18:33:44.766395Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050056784s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T18:33:44.767077Z","caller":"traceutil/trace.go:171","msg":"trace[232205479] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.464184448s","start":"2026-03-06T18:33:43.302855Z","end":"2026-03-06T18:33:44.76704Z","steps":["trace[232205479] 'process raft request' (duration: 1.463883451s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:44.767292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:43.302824Z","time spent":"1.464353852s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:33:45.029845Z","caller":"traceutil/trace.go:171","msg":"trace[2008025577] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"1.726704067s","start":"2026-03-06T18:33:43.303105Z","end":"2026-03-06T18:33:45.029809Z","steps":["trace[2008025577] 'process raft request' (duration: 1.726610155s)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:45.029884Z","caller":"traceutil/trace.go:171","msg":"trace[1256151459] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"1.726868021s","start":"2026-03-06T18:33:43.302985Z","end":"2026-03-06T18:33:45.029853Z","steps":["trace[1256151459] 'process raft request' (duration: 1.726498862s)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:45.0301Z","caller":"traceutil/trace.go:171","msg":"trace[1248909955] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:832; }","duration":"1.723596315s","start":"2026-03-06T18:33:43.306478Z","end":"2026-03-06T18:33:45.030075Z","steps":["trace[1248909955] 'read index received' (duration: 1.460219506s)","trace[1248909955] 'applied index is now lower than readState.Index' (duration: 263.375809ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:33:45.030228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.723731398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:45.030277Z","caller":"traceutil/trace.go:171","msg":"trace[1867107377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"1.723792669s","start":"2026-03-06T18:33:43.30647Z","end":"2026-03-06T18:33:45.030263Z","steps":["trace[1867107377] 'agreement among raft nodes before linearized reading' (duration: 1.723705177s)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:45.03024Z","caller":"traceutil/trace.go:171","msg":"trace[1604030508] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"1.726745967s","start":"2026-03-06T18:33:43.303439Z","end":"2026-03-06T18:33:45.030185Z","steps":["trace[1604030508] 'process raft request' (duration: 1.726328978s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:45.030342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:43.306435Z","time spent":"1.723873131s","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:33:45.03038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.571775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:45.030482Z","caller":"traceutil/trace.go:171","msg":"trace[309113784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"581.78295ms","start":"2026-03-06T18:33:44.448674Z","end":"2026-03-06T18:33:45.030457Z","steps":["trace[309113784] 'agreement among raft nodes before linearized reading' (duration: 581.588745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:45.030513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:43.303374Z","time spent":"1.727011904s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:33:45.030557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:44.448651Z","time spent":"581.893952ms","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:33:45.030734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.490023476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-06T18:33:45.030016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:43.303086Z","time spent":"1.7268359s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:33:45.030922Z","caller":"traceutil/trace.go:171","msg":"trace[417400698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"1.4902208s","start":"2026-03-06T18:33:43.540649Z","end":"2026-03-06T18:33:45.03087Z","steps":["trace[417400698] 'agreement among raft nodes before linearized reading' (duration: 1.489573915s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:45.031004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:43.540627Z","time spent":"1.490363923s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T18:33:45.031182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:43.302961Z","time spent":"1.727113427s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:33:49.624309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.325741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:33:49.624479Z","caller":"traceutil/trace.go:171","msg":"trace[100733824] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:847; }","duration":"542.964762ms","start":"2026-03-06T18:33:49.081497Z","end":"2026-03-06T18:33:49.624461Z","steps":["trace[100733824] 'read index received' (duration: 294.304734ms)","trace[100733824] 'applied index is now lower than readState.Index' (duration: 248.658948ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:33:49.624515Z","caller":"traceutil/trace.go:171","msg":"trace[382460594] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"571.829119ms","start":"2026-03-06T18:33:49.052657Z","end":"2026-03-06T18:33:49.624486Z","steps":["trace[382460594] 'process raft request' (duration: 323.219952ms)","trace[382460594] 'compare' (duration: 248.206228ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:33:49.624563Z","caller":"traceutil/trace.go:171","msg":"trace[1972719386] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"571.566953ms","start":"2026-03-06T18:33:49.052965Z","end":"2026-03-06T18:33:49.624532Z","steps":["trace[1972719386] 'process raft request' (duration: 571.43882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:49.624606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:49.052629Z","time spent":"571.932422ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:33:49.624673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.183927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T18:33:49.624712Z","caller":"traceutil/trace.go:171","msg":"trace[666257651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:817; }","duration":"543.268969ms","start":"2026-03-06T18:33:49.081433Z","end":"2026-03-06T18:33:49.624702Z","steps":["trace[666257651] 'agreement among raft nodes before linearized reading' (duration: 543.125826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:49.624737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:49.052954Z","time spent":"571.676875ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:33:49.624821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:33:49.08141Z","time spent":"543.390372ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T18:33:49.625013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.752936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:49.625053Z","caller":"traceutil/trace.go:171","msg":"trace[1609542962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:817; }","duration":"195.829358ms","start":"2026-03-06T18:33:49.429209Z","end":"2026-03-06T18:33:49.625039Z","steps":["trace[1609542962] 'agreement among raft nodes before linearized reading' (duration: 195.760316ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:49.625117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.634039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:33:49.625239Z","caller":"traceutil/trace.go:171","msg":"trace[910576586] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:817; }","duration":"213.828194ms","start":"2026-03-06T18:33:49.411375Z","end":"2026-03-06T18:33:49.625204Z","steps":["trace[910576586] 'agreement among raft nodes before linearized reading' (duration: 213.589968ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:51.736116Z","caller":"traceutil/trace.go:171","msg":"trace[1882725501] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"102.278504ms","start":"2026-03-06T18:33:51.633803Z","end":"2026-03-06T18:33:51.736082Z","steps":["trace[1882725501] 'process raft request' (duration: 77.392449ms)","trace[1882725501] 'compare' (duration: 24.68205ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:33:56.86564Z","caller":"traceutil/trace.go:171","msg":"trace[1169205223] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"190.176706ms","start":"2026-03-06T18:33:56.675437Z","end":"2026-03-06T18:33:56.865614Z","steps":["trace[1169205223] 'process raft request' (duration: 189.995182ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:57.004315Z","caller":"traceutil/trace.go:171","msg":"trace[227790504] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"136.713371ms","start":"2026-03-06T18:33:56.867578Z","end":"2026-03-06T18:33:57.004291Z","steps":["trace[227790504] 'process raft request' (duration: 136.556168ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:33:57.043225Z","caller":"traceutil/trace.go:171","msg":"trace[539831451] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:886; }","duration":"134.224212ms","start":"2026-03-06T18:33:56.908974Z","end":"2026-03-06T18:33:57.043199Z","steps":["trace[539831451] 'read index received' (duration: 95.219181ms)","trace[539831451] 'applied index is now lower than readState.Index' (duration: 39.003571ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:33:57.043285Z","caller":"traceutil/trace.go:171","msg":"trace[1826389436] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"173.972812ms","start":"2026-03-06T18:33:56.869259Z","end":"2026-03-06T18:33:57.043231Z","steps":["trace[1826389436] 'process raft request' (duration: 173.768237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:33:57.043484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.495359ms","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-03-06T18:33:57.043572Z","caller":"traceutil/trace.go:171","msg":"trace[1333143709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:853; }","duration":"134.636113ms","start":"2026-03-06T18:33:56.908915Z","end":"2026-03-06T18:33:57.043551Z","steps":["trace[1333143709] 'agreement among raft nodes before linearized reading' (duration: 134.396627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:03.418251Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.41924254s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-06T18:34:03.418558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419565587s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T18:34:03.419099Z","caller":"traceutil/trace.go:171","msg":"trace[1297248557] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:935; }","duration":"1.547828853s","start":"2026-03-06T18:34:01.871233Z","end":"2026-03-06T18:34:03.419062Z","steps":["trace[1297248557] 'read index received' (duration: 127.736183ms)","trace[1297248557] 'applied index is now lower than readState.Index' (duration: 1.42009119s)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:34:03.419116Z","caller":"traceutil/trace.go:171","msg":"trace[214679796] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"1.593608921s","start":"2026-03-06T18:34:01.825473Z","end":"2026-03-06T18:34:03.419082Z","steps":["trace[214679796] 'process raft request' (duration: 1.593271123s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:03.419323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:01.825445Z","time spent":"1.593768525s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:34:03.419396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54813859s","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-03-06T18:34:03.419437Z","caller":"traceutil/trace.go:171","msg":"trace[1590940070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"1.548230722s","start":"2026-03-06T18:34:01.871192Z","end":"2026-03-06T18:34:03.419423Z","steps":["trace[1590940070] 'agreement among raft nodes before linearized reading' (duration: 1.548008947s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:03.419478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:01.871173Z","time spent":"1.548295683s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T18:34:03.419552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547801753s","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-03-06T18:34:03.41967Z","caller":"traceutil/trace.go:171","msg":"trace[426807187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:901; }","duration":"1.547941996s","start":"2026-03-06T18:34:01.871702Z","end":"2026-03-06T18:34:03.419644Z","steps":["trace[426807187] 'agreement among raft nodes before linearized reading' (duration: 1.547668329s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:03.419716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:01.871697Z","time spent":"1.548008977s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T18:34:03.419793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.195478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:03.419827Z","caller":"traceutil/trace.go:171","msg":"trace[2070258954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"989.27282ms","start":"2026-03-06T18:34:02.430544Z","end":"2026-03-06T18:34:03.419817Z","steps":["trace[2070258954] 'agreement among raft nodes before linearized reading' (duration: 989.220998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:03.419856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:02.430521Z","time spent":"989.328081ms","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:34:03.419866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.389367ms","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-03-06T18:34:03.419885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284527426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T18:34:03.419913Z","caller":"traceutil/trace.go:171","msg":"trace[961093841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:901; }","duration":"272.474979ms","start":"2026-03-06T18:34:03.147422Z","end":"2026-03-06T18:34:03.419897Z","steps":["trace[961093841] 'agreement among raft nodes before linearized reading' (duration: 272.148152ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:03.419921Z","caller":"traceutil/trace.go:171","msg":"trace[1609431275] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:901; }","duration":"1.284607108s","start":"2026-03-06T18:34:02.135303Z","end":"2026-03-06T18:34:03.41991Z","steps":["trace[1609431275] 'agreement among raft nodes before linearized reading' (duration: 1.284541966s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:03.419954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:02.135277Z","time spent":"1.284669059s","remote":"127.0.0.1:50890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-03-06T18:34:03.420054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.912272ms","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-03-06T18:34:03.42007Z","caller":"traceutil/trace.go:171","msg":"trace[1729054255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"119.966593ms","start":"2026-03-06T18:34:03.300098Z","end":"2026-03-06T18:34:03.420065Z","steps":["trace[1729054255] 'agreement among raft nodes before linearized reading' (duration: 119.919952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:03.420101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.023303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:03.420221Z","caller":"traceutil/trace.go:171","msg":"trace[1124161186] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:901; }","duration":"905.235987ms","start":"2026-03-06T18:34:02.514958Z","end":"2026-03-06T18:34:03.420194Z","steps":["trace[1124161186] 'agreement among raft nodes before linearized reading' (duration: 905.000502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:03.420279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:02.514924Z","time spent":"905.3423ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-03-06T18:34:03.931097Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763152305458360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T18:34:05.374234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.806918ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:05.37438Z","caller":"traceutil/trace.go:171","msg":"trace[2130819244] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:901; }","duration":"741.970271ms","start":"2026-03-06T18:34:04.632392Z","end":"2026-03-06T18:34:05.374363Z","steps":["trace[2130819244] 'range keys from in-memory index tree' (duration: 741.792017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:05.374762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201328991s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:34:05.374808Z","caller":"traceutil/trace.go:171","msg":"trace[2042854370] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"1.949000825s","start":"2026-03-06T18:34:03.425798Z","end":"2026-03-06T18:34:05.374799Z","steps":["trace[2042854370] 'process raft request' (duration: 747.585722ms)","trace[2042854370] 'compare' (duration: 1.200886201s)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:34:05.374868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:03.425765Z","time spent":"1.949069437s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:34:05.378399Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.205017046s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T18:34:05.378877Z","caller":"traceutil/trace.go:171","msg":"trace[456314477] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:937; }","duration":"1.94874739s","start":"2026-03-06T18:34:03.430098Z","end":"2026-03-06T18:34:05.378845Z","steps":["trace[456314477] 'read index received' (duration: 743.276233ms)","trace[456314477] 'applied index is now lower than readState.Index' (duration: 1.205469947s)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:34:05.379248Z","caller":"traceutil/trace.go:171","msg":"trace[990319697] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"1.952120228s","start":"2026-03-06T18:34:03.427113Z","end":"2026-03-06T18:34:05.379234Z","steps":["trace[990319697] 'process raft request' (duration: 1.951508124s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:05.379399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:03.427093Z","time spent":"1.952190219s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:34:05.379693Z","caller":"traceutil/trace.go:171","msg":"trace[1826130229] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"1.952499876s","start":"2026-03-06T18:34:03.427183Z","end":"2026-03-06T18:34:05.379682Z","steps":["trace[1826130229] 'process raft request' (duration: 1.951580275s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:05.379766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:03.427158Z","time spent":"1.952568198s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:34:05.37993Z","caller":"traceutil/trace.go:171","msg":"trace[1973693852] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"1.948033654s","start":"2026-03-06T18:34:03.431886Z","end":"2026-03-06T18:34:05.37992Z","steps":["trace[1973693852] 'process raft request' (duration: 1.946915628s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:05.379991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:03.431859Z","time spent":"1.948099705s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:34:05.380134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.950009659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:05.380237Z","caller":"traceutil/trace.go:171","msg":"trace[985830912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"1.950149473s","start":"2026-03-06T18:34:03.430067Z","end":"2026-03-06T18:34:05.380216Z","steps":["trace[985830912] 'agreement among raft nodes before linearized reading' (duration: 1.949962588s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:05.380283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:03.430056Z","time spent":"1.950217504s","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:34:05.380513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.128664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:05.380542Z","caller":"traceutil/trace.go:171","msg":"trace[966776760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"902.198406ms","start":"2026-03-06T18:34:04.478337Z","end":"2026-03-06T18:34:05.380535Z","steps":["trace[966776760] 'agreement among raft nodes before linearized reading' (duration: 902.153055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:05.380579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:04.4783Z","time spent":"902.274468ms","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:34:05.380665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.37236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T18:34:05.380691Z","caller":"traceutil/trace.go:171","msg":"trace[1787851552] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:905; }","duration":"870.433391ms","start":"2026-03-06T18:34:04.510249Z","end":"2026-03-06T18:34:05.380683Z","steps":["trace[1787851552] 'agreement among raft nodes before linearized reading' (duration: 870.38066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:05.380711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:04.510229Z","time spent":"870.475603ms","remote":"127.0.0.1:50650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-03-06T18:34:15.693881Z","caller":"traceutil/trace.go:171","msg":"trace[1369293853] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"129.519735ms","start":"2026-03-06T18:34:15.56431Z","end":"2026-03-06T18:34:15.69383Z","steps":["trace[1369293853] 'process raft request' (duration: 128.837729ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:16.946801Z","caller":"traceutil/trace.go:171","msg":"trace[1908314230] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"138.47209ms","start":"2026-03-06T18:34:16.808286Z","end":"2026-03-06T18:34:16.946758Z","steps":["trace[1908314230] 'process raft request' (duration: 138.249265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:21.312244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.867877ms","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-03-06T18:34:21.312343Z","caller":"traceutil/trace.go:171","msg":"trace[763875291] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:947; }","duration":"294.99331ms","start":"2026-03-06T18:34:21.017327Z","end":"2026-03-06T18:34:21.31232Z","steps":["trace[763875291] 'count revisions from in-memory index tree' (duration: 294.782205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:22.084341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.070179ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T18:34:22.084457Z","caller":"traceutil/trace.go:171","msg":"trace[477744691] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"602.54124ms","start":"2026-03-06T18:34:21.481896Z","end":"2026-03-06T18:34:22.084437Z","steps":["trace[477744691] 'read index received' (duration: 404.296697ms)","trace[477744691] 'applied index is now lower than readState.Index' (duration: 198.242803ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:34:22.084813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.880917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:22.084901Z","caller":"traceutil/trace.go:171","msg":"trace[1756511720] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:947; }","duration":"603.053751ms","start":"2026-03-06T18:34:21.481827Z","end":"2026-03-06T18:34:22.084881Z","steps":["trace[1756511720] 'agreement among raft nodes before linearized reading' (duration: 602.888347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:22.084991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.837412ms","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-03-06T18:34:22.085026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:21.481807Z","time spent":"603.134313ms","remote":"127.0.0.1:39250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-06T18:34:22.085013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.50399ms","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-03-06T18:34:22.085084Z","caller":"traceutil/trace.go:171","msg":"trace[1480468458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:947; }","duration":"437.990585ms","start":"2026-03-06T18:34:21.647069Z","end":"2026-03-06T18:34:22.085059Z","steps":["trace[1480468458] 'agreement among raft nodes before linearized reading' (duration: 437.729909ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:22.085119Z","caller":"traceutil/trace.go:171","msg":"trace[950902338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:947; }","duration":"330.676844ms","start":"2026-03-06T18:34:21.754421Z","end":"2026-03-06T18:34:22.085097Z","steps":["trace[950902338] 'agreement among raft nodes before linearized reading' (duration: 330.385177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:22.085123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.657705ms","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-03-06T18:34:22.085137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:21.647044Z","time spent":"438.083488ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T18:34:22.085173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:21.754406Z","time spent":"330.757217ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-06T18:34:22.085174Z","caller":"traceutil/trace.go:171","msg":"trace[1800243525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:947; }","duration":"433.771979ms","start":"2026-03-06T18:34:21.651389Z","end":"2026-03-06T18:34:22.085161Z","steps":["trace[1800243525] 'agreement among raft nodes before linearized reading' (duration: 433.583104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:22.085234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:21.651349Z","time spent":"433.86951ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T18:34:22.085323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.724786ms","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-03-06T18:34:22.085397Z","caller":"traceutil/trace.go:171","msg":"trace[1698085688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:947; }","duration":"436.841219ms","start":"2026-03-06T18:34:21.648539Z","end":"2026-03-06T18:34:22.08538Z","steps":["trace[1698085688] 'agreement among raft nodes before linearized reading' (duration: 436.46278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:22.085437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:21.648514Z","time spent":"436.915291ms","remote":"127.0.0.1:50886","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-03-06T18:34:22.085475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.461968ms","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-03-06T18:34:22.085518Z","caller":"traceutil/trace.go:171","msg":"trace[1605602713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:947; }","duration":"432.53806ms","start":"2026-03-06T18:34:21.652969Z","end":"2026-03-06T18:34:22.085507Z","steps":["trace[1605602713] 'agreement among raft nodes before linearized reading' (duration: 431.830113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:22.085554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:21.65296Z","time spent":"432.58825ms","remote":"127.0.0.1:50886","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":"info","ts":"2026-03-06T18:34:27.893507Z","caller":"traceutil/trace.go:171","msg":"trace[280435551] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1025; }","duration":"464.163191ms","start":"2026-03-06T18:34:27.429317Z","end":"2026-03-06T18:34:27.89348Z","steps":["trace[280435551] 'read index received' (duration: 464.002917ms)","trace[280435551] 'applied index is now lower than readState.Index' (duration: 158.943µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:34:27.893608Z","caller":"traceutil/trace.go:171","msg":"trace[1040164994] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"699.755327ms","start":"2026-03-06T18:34:27.193813Z","end":"2026-03-06T18:34:27.893568Z","steps":["trace[1040164994] 'process raft request' (duration: 699.512051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:27.893757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.428556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:27.893803Z","caller":"traceutil/trace.go:171","msg":"trace[124716222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"464.534649ms","start":"2026-03-06T18:34:27.429256Z","end":"2026-03-06T18:34:27.893791Z","steps":["trace[124716222] 'agreement among raft nodes before linearized reading' (duration: 464.321644ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:27.893805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:27.193784Z","time spent":"699.913711ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:34:27.893838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:27.429232Z","time spent":"464.598571ms","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:34:28.677449Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763152305458560,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T18:34:28.838118Z","caller":"traceutil/trace.go:171","msg":"trace[1576098878] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"661.272368ms","start":"2026-03-06T18:34:28.176808Z","end":"2026-03-06T18:34:28.83808Z","steps":["trace[1576098878] 'read index received' (duration: 626.379491ms)","trace[1576098878] 'applied index is now lower than readState.Index' (duration: 34.891217ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:34:28.838169Z","caller":"traceutil/trace.go:171","msg":"trace[1208056353] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"803.352411ms","start":"2026-03-06T18:34:28.034747Z","end":"2026-03-06T18:34:28.8381Z","steps":["trace[1208056353] 'process raft request' (duration: 768.409963ms)","trace[1208056353] 'compare' (duration: 34.370855ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:34:28.838621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.778299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-06T18:34:28.838683Z","caller":"traceutil/trace.go:171","msg":"trace[1844203289] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"661.891941ms","start":"2026-03-06T18:34:28.176774Z","end":"2026-03-06T18:34:28.838666Z","steps":["trace[1844203289] 'agreement among raft nodes before linearized reading' (duration: 661.583084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:28.838727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:28.176757Z","time spent":"661.960033ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T18:34:28.838889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.804901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:28.838972Z","caller":"traceutil/trace.go:171","msg":"trace[308393894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"408.940644ms","start":"2026-03-06T18:34:28.430012Z","end":"2026-03-06T18:34:28.838953Z","steps":["trace[308393894] 'agreement among raft nodes before linearized reading' (duration: 408.807081ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:28.83902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:28.429956Z","time spent":"409.056656ms","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:34:28.839037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:28.034718Z","time spent":"803.546206ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T18:34:28.838958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.521414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-06T18:34:28.839066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.928974ms","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-03-06T18:34:28.838979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.300102ms","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-03-06T18:34:28.839121Z","caller":"traceutil/trace.go:171","msg":"trace[2085852531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:985; }","duration":"655.711698ms","start":"2026-03-06T18:34:28.18339Z","end":"2026-03-06T18:34:28.839102Z","steps":["trace[2085852531] 'agreement among raft nodes before linearized reading' (duration: 655.371441ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:28.839139Z","caller":"traceutil/trace.go:171","msg":"trace[1445524333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:985; }","duration":"656.056287ms","start":"2026-03-06T18:34:28.183064Z","end":"2026-03-06T18:34:28.83912Z","steps":["trace[1445524333] 'agreement among raft nodes before linearized reading' (duration: 655.804171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:28.839188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:28.183042Z","time spent":"656.138578ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T18:34:28.839193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:28.18338Z","time spent":"655.791691ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T18:34:28.839373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.619856ms","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-03-06T18:34:28.839424Z","caller":"traceutil/trace.go:171","msg":"trace[905471525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"658.694708ms","start":"2026-03-06T18:34:28.180716Z","end":"2026-03-06T18:34:28.839411Z","steps":["trace[905471525] 'agreement among raft nodes before linearized reading' (duration: 657.877669ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:28.839429Z","caller":"traceutil/trace.go:171","msg":"trace[1780332106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:985; }","duration":"656.533408ms","start":"2026-03-06T18:34:28.182624Z","end":"2026-03-06T18:34:28.839157Z","steps":["trace[1780332106] 'agreement among raft nodes before linearized reading' (duration: 656.245521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:28.839465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:28.180704Z","time spent":"658.7522ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T18:34:28.839496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:28.182603Z","time spent":"656.875936ms","remote":"127.0.0.1:50886","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":"info","ts":"2026-03-06T18:34:29.128049Z","caller":"traceutil/trace.go:171","msg":"trace[820031211] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"284.758093ms","start":"2026-03-06T18:34:28.843265Z","end":"2026-03-06T18:34:29.128023Z","steps":["trace[820031211] 'process raft request' (duration: 217.691942ms)","trace[820031211] 'compare' (duration: 66.733493ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:34:29.135037Z","caller":"traceutil/trace.go:171","msg":"trace[1120712463] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"291.344115ms","start":"2026-03-06T18:34:28.843678Z","end":"2026-03-06T18:34:29.135022Z","steps":["trace[1120712463] 'process raft request' (duration: 291.283104ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:29.135059Z","caller":"traceutil/trace.go:171","msg":"trace[2093284523] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"291.578061ms","start":"2026-03-06T18:34:28.843444Z","end":"2026-03-06T18:34:29.135022Z","steps":["trace[2093284523] 'process raft request' (duration: 291.431857ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:29.135289Z","caller":"traceutil/trace.go:171","msg":"trace[1498841238] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"291.260453ms","start":"2026-03-06T18:34:28.844Z","end":"2026-03-06T18:34:29.13526Z","steps":["trace[1498841238] 'process raft request' (duration: 290.997137ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:29.135631Z","caller":"traceutil/trace.go:171","msg":"trace[254146065] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"291.397306ms","start":"2026-03-06T18:34:28.844219Z","end":"2026-03-06T18:34:29.135616Z","steps":["trace[254146065] 'process raft request' (duration: 290.982377ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:29.945664Z","caller":"traceutil/trace.go:171","msg":"trace[1886238558] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"133.845944ms","start":"2026-03-06T18:34:29.811793Z","end":"2026-03-06T18:34:29.945639Z","steps":["trace[1886238558] 'process raft request' (duration: 127.510338ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:29.967045Z","caller":"traceutil/trace.go:171","msg":"trace[945569092] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"109.954472ms","start":"2026-03-06T18:34:29.857055Z","end":"2026-03-06T18:34:29.967009Z","steps":["trace[945569092] 'process raft request' (duration: 109.784738ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:30.158659Z","caller":"traceutil/trace.go:171","msg":"trace[743435326] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"205.228904ms","start":"2026-03-06T18:34:29.953397Z","end":"2026-03-06T18:34:30.158626Z","steps":["trace[743435326] 'process raft request' (duration: 135.248446ms)","trace[743435326] 'compare' (duration: 69.826054ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:34:30.158702Z","caller":"traceutil/trace.go:171","msg":"trace[87536476] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"191.717232ms","start":"2026-03-06T18:34:29.966966Z","end":"2026-03-06T18:34:30.158683Z","steps":["trace[87536476] 'read index received' (duration: 121.696083ms)","trace[87536476] 'applied index is now lower than readState.Index' (duration: 70.019489ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:34:30.158874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.601545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-06T18:34:30.158898Z","caller":"traceutil/trace.go:171","msg":"trace[2078970301] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:994; }","duration":"209.701708ms","start":"2026-03-06T18:34:29.949191Z","end":"2026-03-06T18:34:30.158893Z","steps":["trace[2078970301] 'agreement among raft nodes before linearized reading' (duration: 209.559165ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:30.159239Z","caller":"traceutil/trace.go:171","msg":"trace[1516755157] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"188.447617ms","start":"2026-03-06T18:34:29.970785Z","end":"2026-03-06T18:34:30.159233Z","steps":["trace[1516755157] 'process raft request' (duration: 187.864983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:31.684149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254554273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:31.684231Z","caller":"traceutil/trace.go:171","msg":"trace[498726401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"1.254692555s","start":"2026-03-06T18:34:30.429522Z","end":"2026-03-06T18:34:31.684215Z","steps":["trace[498726401] 'range keys from in-memory index tree' (duration: 1.254496611s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:31.684266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:30.429507Z","time spent":"1.254752247s","remote":"127.0.0.1:50626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:34:31.684157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.420974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T18:34:31.684741Z","caller":"traceutil/trace.go:171","msg":"trace[1054814168] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:996; }","duration":"724.049669ms","start":"2026-03-06T18:34:30.960671Z","end":"2026-03-06T18:34:31.684721Z","steps":["trace[1054814168] 'count revisions from in-memory index tree' (duration: 723.331502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:31.684817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:30.960653Z","time spent":"724.142861ms","remote":"127.0.0.1:51082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-03-06T18:34:31.684992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.80302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:34:31.685055Z","caller":"traceutil/trace.go:171","msg":"trace[1774030099] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"550.370404ms","start":"2026-03-06T18:34:31.134674Z","end":"2026-03-06T18:34:31.685044Z","steps":["trace[1774030099] 'read index received' (duration: 43.111µs)","trace[1774030099] 'applied index is now lower than readState.Index' (duration: 550.326373ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:34:31.685195Z","caller":"traceutil/trace.go:171","msg":"trace[787815785] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"718.05646ms","start":"2026-03-06T18:34:30.96713Z","end":"2026-03-06T18:34:31.685186Z","steps":["trace[787815785] 'process raft request' (duration: 27.004164ms)","trace[787815785] 'compare' (duration: 690.38748ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:34:31.68523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:30.967115Z","time spent":"718.096211ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:34:31.685278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.609879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T18:34:31.685315Z","caller":"traceutil/trace.go:171","msg":"trace[548795833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:997; }","duration":"550.679741ms","start":"2026-03-06T18:34:31.134623Z","end":"2026-03-06T18:34:31.685303Z","steps":["trace[548795833] 'agreement among raft nodes before linearized reading' (duration: 550.543487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:31.685346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:31.134601Z","time spent":"550.737402ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T18:34:32.185329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763152305458584,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T18:34:32.83764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.669812ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T18:34:32.838206Z","caller":"traceutil/trace.go:171","msg":"trace[1871533043] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1039; }","duration":"1.153111027s","start":"2026-03-06T18:34:31.685068Z","end":"2026-03-06T18:34:32.838179Z","steps":["trace[1871533043] 'read index received' (duration: 825.905933ms)","trace[1871533043] 'applied index is now lower than readState.Index' (duration: 327.203664ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:34:32.83856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.659486904s","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-03-06T18:34:32.838559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.660149808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-06T18:34:32.838591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145192904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:32.838669Z","caller":"traceutil/trace.go:171","msg":"trace[830556625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:998; }","duration":"1.660292012s","start":"2026-03-06T18:34:31.178361Z","end":"2026-03-06T18:34:32.838653Z","steps":["trace[830556625] 'agreement among raft nodes before linearized reading' (duration: 1.659894433s)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:32.838669Z","caller":"traceutil/trace.go:171","msg":"trace[647908399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:998; }","duration":"1.659600106s","start":"2026-03-06T18:34:31.179043Z","end":"2026-03-06T18:34:32.838643Z","steps":["trace[647908399] 'agreement among raft nodes before linearized reading' (duration: 1.659163446s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:32.83876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:31.178355Z","time spent":"1.660358274s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T18:34:32.83875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:31.179038Z","time spent":"1.659696318s","remote":"127.0.0.1:50886","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":"info","ts":"2026-03-06T18:34:32.838819Z","caller":"traceutil/trace.go:171","msg":"trace[661161826] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.1501747s","start":"2026-03-06T18:34:31.688618Z","end":"2026-03-06T18:34:32.838793Z","steps":["trace[661161826] 'process raft request' (duration: 1.149151906s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:32.838962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:31.688601Z","time spent":"1.150274492s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:34:32.839172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.69998973s","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-03-06T18:34:32.838663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.698634289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-06T18:34:32.839352Z","caller":"traceutil/trace.go:171","msg":"trace[740375864] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:998; }","duration":"1.699352095s","start":"2026-03-06T18:34:31.139979Z","end":"2026-03-06T18:34:32.839331Z","steps":["trace[740375864] 'agreement among raft nodes before linearized reading' (duration: 1.698385513s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:32.839407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:31.139971Z","time spent":"1.699418917s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-06T18:34:32.839558Z","caller":"traceutil/trace.go:171","msg":"trace[288444412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:998; }","duration":"1.700296527s","start":"2026-03-06T18:34:31.139122Z","end":"2026-03-06T18:34:32.839419Z","steps":["trace[288444412] 'agreement among raft nodes before linearized reading' (duration: 1.699447447s)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:32.839211Z","caller":"traceutil/trace.go:171","msg":"trace[549923400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"1.145368268s","start":"2026-03-06T18:34:31.693333Z","end":"2026-03-06T18:34:32.838701Z","steps":["trace[549923400] 'agreement among raft nodes before linearized reading' (duration: 1.145151543s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:32.839703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:31.693324Z","time spent":"1.146365821s","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:34:32.839726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:31.139102Z","time spent":"1.700560844s","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-06T18:34:33.09526Z","caller":"traceutil/trace.go:171","msg":"trace[1899875158] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1041; }","duration":"246.343995ms","start":"2026-03-06T18:34:32.848889Z","end":"2026-03-06T18:34:33.095233Z","steps":["trace[1899875158] 'read index received' (duration: 39.235617ms)","trace[1899875158] 'applied index is now lower than readState.Index' (duration: 207.107488ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:34:33.095416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.513289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:33.095391Z","caller":"traceutil/trace.go:171","msg":"trace[1461609331] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"249.404476ms","start":"2026-03-06T18:34:32.845949Z","end":"2026-03-06T18:34:33.095353Z","steps":["trace[1461609331] 'process raft request' (duration: 249.16144ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:33.095471Z","caller":"traceutil/trace.go:171","msg":"trace[2112316321] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"248.600187ms","start":"2026-03-06T18:34:32.846863Z","end":"2026-03-06T18:34:33.095463Z","steps":["trace[2112316321] 'process raft request' (duration: 248.333181ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:33.095452Z","caller":"traceutil/trace.go:171","msg":"trace[2142743810] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"249.906777ms","start":"2026-03-06T18:34:32.845509Z","end":"2026-03-06T18:34:33.095416Z","steps":["trace[2142743810] 'process raft request' (duration: 193.459392ms)","trace[2142743810] 'compare' (duration: 55.831551ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:34:33.095443Z","caller":"traceutil/trace.go:171","msg":"trace[1546318886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"246.586171ms","start":"2026-03-06T18:34:32.848847Z","end":"2026-03-06T18:34:33.095434Z","steps":["trace[1546318886] 'agreement among raft nodes before linearized reading' (duration: 246.489779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:33.612639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.765262ms","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-03-06T18:34:33.612776Z","caller":"traceutil/trace.go:171","msg":"trace[1342915851] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1002; }","duration":"245.941916ms","start":"2026-03-06T18:34:33.366807Z","end":"2026-03-06T18:34:33.612749Z","steps":["trace[1342915851] 'count revisions from in-memory index tree' (duration: 245.640198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:35.392914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763152305458599,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T18:34:35.641713Z","caller":"traceutil/trace.go:171","msg":"trace[621905730] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"749.66897ms","start":"2026-03-06T18:34:34.892013Z","end":"2026-03-06T18:34:35.641682Z","steps":["trace[621905730] 'read index received' (duration: 749.2075ms)","trace[621905730] 'applied index is now lower than readState.Index' (duration: 459.86µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:34:35.641773Z","caller":"traceutil/trace.go:171","msg":"trace[2077217104] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"791.094158ms","start":"2026-03-06T18:34:34.850638Z","end":"2026-03-06T18:34:35.641732Z","steps":["trace[2077217104] 'process raft request' (duration: 790.799161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:35.641956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:34.850601Z","time spent":"791.262502ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:34:35.642044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.952989ms","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-03-06T18:34:35.642115Z","caller":"traceutil/trace.go:171","msg":"trace[980150009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1003; }","duration":"542.072682ms","start":"2026-03-06T18:34:35.100025Z","end":"2026-03-06T18:34:35.642098Z","steps":["trace[980150009] 'agreement among raft nodes before linearized reading' (duration: 541.854567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:35.642129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.123237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T18:34:35.642174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:35.100004Z","time spent":"542.155294ms","remote":"127.0.0.1:50886","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-03-06T18:34:35.642211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.143161ms","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-03-06T18:34:35.642361Z","caller":"traceutil/trace.go:171","msg":"trace[507570073] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1003; }","duration":"750.342526ms","start":"2026-03-06T18:34:34.891959Z","end":"2026-03-06T18:34:35.642302Z","steps":["trace[507570073] 'agreement among raft nodes before linearized reading' (duration: 749.890585ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:35.642176Z","caller":"traceutil/trace.go:171","msg":"trace[1858317327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"213.200929ms","start":"2026-03-06T18:34:35.42896Z","end":"2026-03-06T18:34:35.642161Z","steps":["trace[1858317327] 'agreement among raft nodes before linearized reading' (duration: 213.137137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:35.642056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.804152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-06T18:34:35.642516Z","caller":"traceutil/trace.go:171","msg":"trace[764432006] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1003; }","duration":"403.309193ms","start":"2026-03-06T18:34:35.23919Z","end":"2026-03-06T18:34:35.642499Z","steps":["trace[764432006] 'agreement among raft nodes before linearized reading' (duration: 402.759091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:35.642551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.4537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-06T18:34:35.642603Z","caller":"traceutil/trace.go:171","msg":"trace[1930102045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1003; }","duration":"542.553183ms","start":"2026-03-06T18:34:35.100038Z","end":"2026-03-06T18:34:35.642591Z","steps":["trace[1930102045] 'agreement among raft nodes before linearized reading' (duration: 542.350448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:35.642641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:35.100015Z","time spent":"542.619385ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T18:34:35.642569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:35.239168Z","time spent":"403.386576ms","remote":"127.0.0.1:50886","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":"warn","ts":"2026-03-06T18:34:35.642777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.575764ms","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-03-06T18:34:35.642841Z","caller":"traceutil/trace.go:171","msg":"trace[253137233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1003; }","duration":"542.711347ms","start":"2026-03-06T18:34:35.10011Z","end":"2026-03-06T18:34:35.642822Z","steps":["trace[253137233] 'agreement among raft nodes before linearized reading' (duration: 542.564183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:35.642889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:35.100059Z","time spent":"542.819249ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T18:34:35.64241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:34.891939Z","time spent":"750.462409ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-06T18:34:35.817474Z","caller":"traceutil/trace.go:171","msg":"trace[793066353] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"168.590917ms","start":"2026-03-06T18:34:35.648861Z","end":"2026-03-06T18:34:35.817452Z","steps":["trace[793066353] 'process raft request' (duration: 168.511595ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:35.817687Z","caller":"traceutil/trace.go:171","msg":"trace[1792750252] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"169.351425ms","start":"2026-03-06T18:34:35.648326Z","end":"2026-03-06T18:34:35.817677Z","steps":["trace[1792750252] 'process raft request' (duration: 168.930695ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:35.817686Z","caller":"traceutil/trace.go:171","msg":"trace[1227842380] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"167.866491ms","start":"2026-03-06T18:34:35.649802Z","end":"2026-03-06T18:34:35.817668Z","steps":["trace[1227842380] 'process raft request' (duration: 167.609765ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:35.817904Z","caller":"traceutil/trace.go:171","msg":"trace[1686338545] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"167.995563ms","start":"2026-03-06T18:34:35.649899Z","end":"2026-03-06T18:34:35.817894Z","steps":["trace[1686338545] 'process raft request' (duration: 167.720777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:38.382456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.786028088s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:34:38.38263Z","caller":"traceutil/trace.go:171","msg":"trace[298760450] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"1.657393284s","start":"2026-03-06T18:34:36.725198Z","end":"2026-03-06T18:34:38.382591Z","steps":["trace[298760450] 'read index received' (duration: 41.331µs)","trace[298760450] 'applied index is now lower than readState.Index' (duration: 1.657311342s)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:34:38.382762Z","caller":"traceutil/trace.go:171","msg":"trace[959063494] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.87781962s","start":"2026-03-06T18:34:36.50493Z","end":"2026-03-06T18:34:38.38275Z","steps":["trace[959063494] 'process raft request' (duration: 91.412223ms)","trace[959063494] 'compare' (duration: 1.775884094s)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:34:38.382802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:36.50491Z","time spent":"1.877867922s","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:34:38.383195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658002799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:38.383224Z","caller":"traceutil/trace.go:171","msg":"trace[812073099] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1009; }","duration":"1.658074461s","start":"2026-03-06T18:34:36.725137Z","end":"2026-03-06T18:34:38.383212Z","steps":["trace[812073099] 'agreement among raft nodes before linearized reading' (duration: 1.657808564s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:38.383307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:36.725112Z","time spent":"1.658125031s","remote":"127.0.0.1:50692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-03-06T18:34:38.41023Z","caller":"traceutil/trace.go:171","msg":"trace[217820910] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"246.481177ms","start":"2026-03-06T18:34:38.163724Z","end":"2026-03-06T18:34:38.410205Z","steps":["trace[217820910] 'process raft request' (duration: 246.054548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:38.41076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.588324ms","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-03-06T18:34:38.410812Z","caller":"traceutil/trace.go:171","msg":"trace[813949147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"587.673106ms","start":"2026-03-06T18:34:37.823125Z","end":"2026-03-06T18:34:38.410798Z","steps":["trace[813949147] 'agreement among raft nodes before linearized reading' (duration: 587.485412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:38.41095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4135644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T18:34:38.411039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.019427ms","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-03-06T18:34:38.411097Z","caller":"traceutil/trace.go:171","msg":"trace[35250728] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1010; }","duration":"1.413808025s","start":"2026-03-06T18:34:36.997254Z","end":"2026-03-06T18:34:38.411062Z","steps":["trace[35250728] 'agreement among raft nodes before linearized reading' (duration: 1.413523149s)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:38.41113Z","caller":"traceutil/trace.go:171","msg":"trace[1006253172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1010; }","duration":"711.14683ms","start":"2026-03-06T18:34:37.699961Z","end":"2026-03-06T18:34:38.411107Z","steps":["trace[1006253172] 'agreement among raft nodes before linearized reading' (duration: 710.909825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:38.411189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:36.997226Z","time spent":"1.413943419s","remote":"127.0.0.1:39254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-06T18:34:38.410937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:37.823116Z","time spent":"587.808199ms","remote":"127.0.0.1:50886","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-03-06T18:34:38.411226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:37.699945Z","time spent":"711.268483ms","remote":"127.0.0.1:50886","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-03-06T18:34:38.410813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.581678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:38.411368Z","caller":"traceutil/trace.go:171","msg":"trace[934410603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"981.195192ms","start":"2026-03-06T18:34:37.430159Z","end":"2026-03-06T18:34:38.411354Z","steps":["trace[934410603] 'agreement among raft nodes before linearized reading' (duration: 980.547207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:38.411414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:37.430135Z","time spent":"981.267354ms","remote":"127.0.0.1:50626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:34:38.41111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.262262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-06T18:34:38.411517Z","caller":"traceutil/trace.go:171","msg":"trace[1346207076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1010; }","duration":"589.678862ms","start":"2026-03-06T18:34:37.821821Z","end":"2026-03-06T18:34:38.4115Z","steps":["trace[1346207076] 'agreement among raft nodes before linearized reading' (duration: 589.217162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:38.411592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:37.821813Z","time spent":"589.766624ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T18:34:38.412027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.757612ms","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-03-06T18:34:38.412089Z","caller":"traceutil/trace.go:171","msg":"trace[1101581726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1010; }","duration":"752.867675ms","start":"2026-03-06T18:34:37.659202Z","end":"2026-03-06T18:34:38.41207Z","steps":["trace[1101581726] 'agreement among raft nodes before linearized reading' (duration: 752.66979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:38.412127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:37.65918Z","time spent":"752.938137ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T18:34:38.412608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.380632ms","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-03-06T18:34:38.412666Z","caller":"traceutil/trace.go:171","msg":"trace[724948292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1010; }","duration":"591.479494ms","start":"2026-03-06T18:34:37.821173Z","end":"2026-03-06T18:34:38.412652Z","steps":["trace[724948292] 'agreement among raft nodes before linearized reading' (duration: 589.666202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:38.412699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:37.821158Z","time spent":"591.533485ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-06T18:34:40.692541Z","caller":"traceutil/trace.go:171","msg":"trace[1729947455] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"147.688324ms","start":"2026-03-06T18:34:40.544834Z","end":"2026-03-06T18:34:40.692522Z","steps":["trace[1729947455] 'process raft request' (duration: 147.628323ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:40.692585Z","caller":"traceutil/trace.go:171","msg":"trace[213710776] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"149.197749ms","start":"2026-03-06T18:34:40.543358Z","end":"2026-03-06T18:34:40.692555Z","steps":["trace[213710776] 'process raft request' (duration: 147.464059ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:40.692982Z","caller":"traceutil/trace.go:171","msg":"trace[297796201] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"147.95487ms","start":"2026-03-06T18:34:40.545018Z","end":"2026-03-06T18:34:40.692973Z","steps":["trace[297796201] 'process raft request' (duration: 147.880649ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:40.693034Z","caller":"traceutil/trace.go:171","msg":"trace[2061203462] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"148.072483ms","start":"2026-03-06T18:34:40.544951Z","end":"2026-03-06T18:34:40.693024Z","steps":["trace[2061203462] 'process raft request' (duration: 147.54215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:41.937691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.098046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:34:41.938107Z","caller":"traceutil/trace.go:171","msg":"trace[320601532] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"560.347814ms","start":"2026-03-06T18:34:41.377739Z","end":"2026-03-06T18:34:41.938087Z","steps":["trace[320601532] 'process raft request' (duration: 560.059277ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:34:41.938169Z","caller":"traceutil/trace.go:171","msg":"trace[1600021457] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"560.488887ms","start":"2026-03-06T18:34:41.377652Z","end":"2026-03-06T18:34:41.93814Z","steps":["trace[1600021457] 'process raft request' (duration: 83.835248ms)","trace[1600021457] 'compare' (duration: 475.921272ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:34:41.938222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:41.377725Z","time spent":"560.443807ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T18:34:41.938282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:41.377638Z","time spent":"560.58995ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T18:34:41.938268Z","caller":"traceutil/trace.go:171","msg":"trace[1779499546] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1075; }","duration":"508.188819ms","start":"2026-03-06T18:34:41.430024Z","end":"2026-03-06T18:34:41.938213Z","steps":["trace[1779499546] 'read index received' (duration: 31.506249ms)","trace[1779499546] 'applied index is now lower than readState.Index' (duration: 476.67272ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:34:41.943789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.727157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:34:41.943895Z","caller":"traceutil/trace.go:171","msg":"trace[1053180110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1033; }","duration":"513.891251ms","start":"2026-03-06T18:34:41.429979Z","end":"2026-03-06T18:34:41.943871Z","steps":["trace[1053180110] 'agreement among raft nodes before linearized reading' (duration: 508.358423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:41.943962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:41.429959Z","time spent":"513.976352ms","remote":"127.0.0.1:50618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T18:34:43.996739Z","caller":"traceutil/trace.go:171","msg":"trace[297304599] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"346.683945ms","start":"2026-03-06T18:34:43.650029Z","end":"2026-03-06T18:34:43.996713Z","steps":["trace[297304599] 'process raft request' (duration: 346.47375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:34:43.996948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:34:43.650008Z","time spent":"346.858448ms","remote":"127.0.0.1:50800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13708,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:35:37.29146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.344491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:35:37.291798Z","caller":"traceutil/trace.go:171","msg":"trace[165320563] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"121.806274ms","start":"2026-03-06T18:35:37.169794Z","end":"2026-03-06T18:35:37.2916Z","steps":["trace[165320563] 'process raft request' (duration: 14.220597ms)","trace[165320563] 'compare' (duration: 107.193618ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:35:47.636495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.905822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:35:47.636721Z","caller":"traceutil/trace.go:171","msg":"trace[1439469523] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"230.246681ms","start":"2026-03-06T18:35:47.406448Z","end":"2026-03-06T18:35:47.636694Z","steps":["trace[1439469523] 'read index received' (duration: 89.949859ms)","trace[1439469523] 'applied index is now lower than readState.Index' (duration: 140.294862ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:35:47.636855Z","caller":"traceutil/trace.go:171","msg":"trace[1795064785] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"231.200383ms","start":"2026-03-06T18:35:47.405608Z","end":"2026-03-06T18:35:47.636808Z","steps":["trace[1795064785] 'process raft request' (duration: 90.770469ms)","trace[1795064785] 'compare' (duration: 139.700277ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:35:47.637071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.682467ms","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-03-06T18:35:47.637122Z","caller":"traceutil/trace.go:171","msg":"trace[1706456741] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1320; }","duration":"218.797921ms","start":"2026-03-06T18:35:47.418309Z","end":"2026-03-06T18:35:47.637107Z","steps":["trace[1706456741] 'agreement among raft nodes before linearized reading' (duration: 218.698728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:35:47.637271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.83501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:35:47.637344Z","caller":"traceutil/trace.go:171","msg":"trace[36680550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"208.750702ms","start":"2026-03-06T18:35:47.428573Z","end":"2026-03-06T18:35:47.637323Z","steps":["trace[36680550] 'agreement among raft nodes before linearized reading' (duration: 208.596628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:35:47.637411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.874105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-06T18:35:47.637472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.290014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-06T18:35:47.637424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.164337ms","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-03-06T18:35:47.637621Z","caller":"traceutil/trace.go:171","msg":"trace[744622112] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1320; }","duration":"168.408797ms","start":"2026-03-06T18:35:47.469138Z","end":"2026-03-06T18:35:47.637547Z","steps":["trace[744622112] 'agreement among raft nodes before linearized reading' (duration: 167.967417ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:35:47.637644Z","caller":"traceutil/trace.go:171","msg":"trace[412086862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1320; }","duration":"194.383702ms","start":"2026-03-06T18:35:47.443239Z","end":"2026-03-06T18:35:47.637622Z","steps":["trace[412086862] 'agreement among raft nodes before linearized reading' (duration: 193.927231ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:35:47.637533Z","caller":"traceutil/trace.go:171","msg":"trace[2125092705] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1320; }","duration":"231.141192ms","start":"2026-03-06T18:35:47.406365Z","end":"2026-03-06T18:35:47.637506Z","steps":["trace[2125092705] 'agreement among raft nodes before linearized reading' (duration: 230.571218ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:35:49.78142Z","caller":"traceutil/trace.go:171","msg":"trace[1960762860] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"107.734171ms","start":"2026-03-06T18:35:49.673654Z","end":"2026-03-06T18:35:49.781388Z","steps":["trace[1960762860] 'process raft request' (duration: 56.844776ms)","trace[1960762860] 'compare' (duration: 50.734831ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:35:49.78169Z","caller":"traceutil/trace.go:171","msg":"trace[881003669] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"107.188317ms","start":"2026-03-06T18:35:49.674476Z","end":"2026-03-06T18:35:49.781664Z","steps":["trace[881003669] 'process raft request' (duration: 106.87182ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:35:50.223151Z","caller":"traceutil/trace.go:171","msg":"trace[1583274031] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"143.041167ms","start":"2026-03-06T18:35:50.080079Z","end":"2026-03-06T18:35:50.22312Z","steps":["trace[1583274031] 'process raft request' (duration: 141.020049ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:37:48.495216Z","caller":"traceutil/trace.go:171","msg":"trace[536740231] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"123.267898ms","start":"2026-03-06T18:37:48.371912Z","end":"2026-03-06T18:37:48.49518Z","steps":["trace[536740231] 'process raft request' (duration: 123.054692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:37:48.788124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.130479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-06T18:37:48.788236Z","caller":"traceutil/trace.go:171","msg":"trace[491715717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1837; }","duration":"242.310794ms","start":"2026-03-06T18:37:48.545903Z","end":"2026-03-06T18:37:48.788214Z","steps":["trace[491715717] 'range keys from in-memory index tree' (duration: 241.883603ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:37:56.960122Z","caller":"traceutil/trace.go:171","msg":"trace[246830145] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"119.490288ms","start":"2026-03-06T18:37:56.840597Z","end":"2026-03-06T18:37:56.960087Z","steps":["trace[246830145] 'process raft request' (duration: 119.287463ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:37:56.996588Z","caller":"traceutil/trace.go:171","msg":"trace[453746570] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"153.680817ms","start":"2026-03-06T18:37:56.842858Z","end":"2026-03-06T18:37:56.996539Z","steps":["trace[453746570] 'process raft request' (duration: 153.627906ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:37:56.996627Z","caller":"traceutil/trace.go:171","msg":"trace[2049387162] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"154.579328ms","start":"2026-03-06T18:37:56.842012Z","end":"2026-03-06T18:37:56.996591Z","steps":["trace[2049387162] 'process raft request' (duration: 154.343993ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:37:57.603003Z","caller":"traceutil/trace.go:171","msg":"trace[472842640] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1964; }","duration":"173.434222ms","start":"2026-03-06T18:37:57.429541Z","end":"2026-03-06T18:37:57.602975Z","steps":["trace[472842640] 'read index received' (duration: 171.803233ms)","trace[472842640] 'applied index is now lower than readState.Index' (duration: 1.629689ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:37:57.603162Z","caller":"traceutil/trace.go:171","msg":"trace[1791054191] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"357.614042ms","start":"2026-03-06T18:37:57.245514Z","end":"2026-03-06T18:37:57.603128Z","steps":["trace[1791054191] 'process raft request' (duration: 355.903491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:37:57.603338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:37:57.245481Z","time spent":"357.768975ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:37:57.603349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.790781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:37:57.603389Z","caller":"traceutil/trace.go:171","msg":"trace[1772434534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1880; }","duration":"173.854452ms","start":"2026-03-06T18:37:57.429521Z","end":"2026-03-06T18:37:57.603376Z","steps":["trace[1772434534] 'agreement among raft nodes before linearized reading' (duration: 173.76374ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:38:16.510273Z","caller":"traceutil/trace.go:171","msg":"trace[1251403460] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2049; }","duration":"132.056669ms","start":"2026-03-06T18:38:16.378153Z","end":"2026-03-06T18:38:16.51021Z","steps":["trace[1251403460] 'read index received' (duration: 97.592552ms)","trace[1251403460] 'applied index is now lower than readState.Index' (duration: 34.462967ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:38:16.510359Z","caller":"traceutil/trace.go:171","msg":"trace[887082736] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"133.026752ms","start":"2026-03-06T18:38:16.377261Z","end":"2026-03-06T18:38:16.510288Z","steps":["trace[887082736] 'process raft request' (duration: 132.723535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:38:16.510594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.401187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-03-06T18:38:16.510724Z","caller":"traceutil/trace.go:171","msg":"trace[1560475766] range","detail":"{range_begin:/registry/configmaps/kube-public/kube-root-ca.crt; range_end:; response_count:1; response_revision:1961; }","duration":"132.564721ms","start":"2026-03-06T18:38:16.378134Z","end":"2026-03-06T18:38:16.510699Z","steps":["trace[1560475766] 'agreement among raft nodes before linearized reading' (duration: 132.284214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:38:16.511878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.356096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:38:16.511943Z","caller":"traceutil/trace.go:171","msg":"trace[591966421] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-node-lease/; range_end:/registry/persistentvolumeclaims/kube-node-lease0; response_count:0; response_revision:1962; }","duration":"107.425928ms","start":"2026-03-06T18:38:16.404499Z","end":"2026-03-06T18:38:16.511925Z","steps":["trace[591966421] 'agreement among raft nodes before linearized reading' (duration: 107.287024ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:38:17.794808Z","caller":"traceutil/trace.go:171","msg":"trace[1427419012] linearizableReadLoop","detail":"{readStateIndex:2059; appliedIndex:2058; }","duration":"110.217485ms","start":"2026-03-06T18:38:17.684567Z","end":"2026-03-06T18:38:17.794785Z","steps":["trace[1427419012] 'read index received' (duration: 109.907777ms)","trace[1427419012] 'applied index is now lower than readState.Index' (duration: 308.238µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:38:17.794859Z","caller":"traceutil/trace.go:171","msg":"trace[981930105] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"132.94105ms","start":"2026-03-06T18:38:17.661897Z","end":"2026-03-06T18:38:17.794838Z","steps":["trace[981930105] 'process raft request' (duration: 132.736935ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:38:17.794978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.740391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-system/\" range_end:\"/registry/ingress/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:38:17.795026Z","caller":"traceutil/trace.go:171","msg":"trace[201920463] range","detail":"{range_begin:/registry/ingress/kube-system/; range_end:/registry/ingress/kube-system0; response_count:0; response_revision:1969; }","duration":"101.827023ms","start":"2026-03-06T18:38:17.693179Z","end":"2026-03-06T18:38:17.795006Z","steps":["trace[201920463] 'agreement among raft nodes before linearized reading' (duration: 101.734921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:38:17.79513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.520812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/\" range_end:\"/registry/services/endpoints/kube-system0\" limit:500 ","response":"range_response_count:3 size:2613"} {"level":"info","ts":"2026-03-06T18:38:17.795238Z","caller":"traceutil/trace.go:171","msg":"trace[1094652144] range","detail":"{range_begin:/registry/services/endpoints/kube-system/; range_end:/registry/services/endpoints/kube-system0; response_count:3; response_revision:1969; }","duration":"110.695866ms","start":"2026-03-06T18:38:17.684516Z","end":"2026-03-06T18:38:17.795212Z","steps":["trace[1094652144] 'agreement among raft nodes before linearized reading' (duration: 110.369958ms)"],"step_count":1}