{"level":"warn","ts":"2026-02-24T13:29:15.475778Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T13:29:15.475873Z","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-02-24T13:29:15.476013Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T13:29:15.476029Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-24T13:29:15.47605Z","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-02-24T13:29:15.477917Z","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-02-24T13:29:15.478021Z","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-02-24T13:29:15.484181Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.925579ms"} {"level":"info","ts":"2026-02-24T13:29:15.493896Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-02-24T13:29:15.493987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T13:29:15.494017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-02-24T13:29:15.494028Z","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-02-24T13:29:15.494041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-02-24T13:29:15.494083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-02-24T13:29:15.507381Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T13:29:15.508879Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T13:29:15.51012Z","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-02-24T13:29:15.513336Z","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-02-24T13:29:15.51388Z","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-02-24T13:29:15.514292Z","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-02-24T13:29:15.51432Z","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-02-24T13:29:15.514577Z","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-02-24T13:29:15.520519Z","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-02-24T13:29:15.520727Z","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-02-24T13:29:15.520762Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T13:29:15.520852Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-24T13:29:15.520875Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-24T13:29:15.521731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-02-24T13:29:15.521818Z","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-02-24T13:29:15.594723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T13:29:15.594797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T13:29:15.594834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-02-24T13:29:15.594855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-02-24T13:29:15.594866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-24T13:29:15.594889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-02-24T13:29:15.594909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-24T13:29:15.596018Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T13:29:15.596776Z","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-02-24T13:29:15.596788Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T13:29:15.597102Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T13:29:15.597168Z","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-02-24T13:29:15.597546Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T13:29:15.597588Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T13:29:15.597646Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T13:29:15.597728Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T13:29:15.600751Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-02-24T13:29:15.600858Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T13:29:24.643804Z","caller":"traceutil/trace.go:171","msg":"trace[970161977] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"168.03781ms","start":"2026-02-24T13:29:24.475724Z","end":"2026-02-24T13:29:24.643762Z","steps":["trace[970161977] 'process raft request' (duration: 122.058271ms)","trace[970161977] 'compare' (duration: 45.701373ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:29:24.643988Z","caller":"traceutil/trace.go:171","msg":"trace[1822117670] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:318; }","duration":"131.540059ms","start":"2026-02-24T13:29:24.512421Z","end":"2026-02-24T13:29:24.643961Z","steps":["trace[1822117670] 'read index received' (duration: 85.375755ms)","trace[1822117670] 'applied index is now lower than readState.Index' (duration: 46.163304ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:29:24.644086Z","caller":"traceutil/trace.go:171","msg":"trace[454226944] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"167.037318ms","start":"2026-02-24T13:29:24.477006Z","end":"2026-02-24T13:29:24.644044Z","steps":["trace[454226944] 'process raft request' (duration: 166.741401ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:29:24.644126Z","caller":"traceutil/trace.go:171","msg":"trace[1472329917] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"165.333761ms","start":"2026-02-24T13:29:24.478765Z","end":"2026-02-24T13:29:24.644098Z","steps":["trace[1472329917] 'process raft request' (duration: 165.134466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:29:24.644173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.728884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-24T13:29:24.644269Z","caller":"traceutil/trace.go:171","msg":"trace[405400137] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:310; }","duration":"131.867147ms","start":"2026-02-24T13:29:24.512376Z","end":"2026-02-24T13:29:24.644243Z","steps":["trace[405400137] 'agreement among raft nodes before linearized reading' (duration: 131.731393ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:29:25.132707Z","caller":"traceutil/trace.go:171","msg":"trace[797030948] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"135.595839ms","start":"2026-02-24T13:29:24.997085Z","end":"2026-02-24T13:29:25.13268Z","steps":["trace[797030948] 'process raft request' (duration: 135.516987ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:29:25.132808Z","caller":"traceutil/trace.go:171","msg":"trace[1938530107] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"136.58004ms","start":"2026-02-24T13:29:24.996155Z","end":"2026-02-24T13:29:25.132735Z","steps":["trace[1938530107] 'process raft request' (duration: 62.019572ms)","trace[1938530107] 'compare' (duration: 74.19332ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:29:25.138337Z","caller":"traceutil/trace.go:171","msg":"trace[1125880722] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"136.13745ms","start":"2026-02-24T13:29:25.002181Z","end":"2026-02-24T13:29:25.138319Z","steps":["trace[1125880722] 'process raft request' (duration: 135.915696ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:29:25.138372Z","caller":"traceutil/trace.go:171","msg":"trace[618030927] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"133.899101ms","start":"2026-02-24T13:29:25.004445Z","end":"2026-02-24T13:29:25.138344Z","steps":["trace[618030927] 'process raft request' (duration: 133.767818ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:29:46.925705Z","caller":"traceutil/trace.go:171","msg":"trace[1756575067] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"182.369455ms","start":"2026-02-24T13:29:46.743315Z","end":"2026-02-24T13:29:46.925684Z","steps":["trace[1756575067] 'process raft request' (duration: 182.241422ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:29:51.475664Z","caller":"traceutil/trace.go:171","msg":"trace[61745159] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"106.275405ms","start":"2026-02-24T13:29:51.369344Z","end":"2026-02-24T13:29:51.47562Z","steps":["trace[61745159] 'process raft request' (duration: 106.008739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:29:54.053805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.654855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kt6f9\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-24T13:29:54.053895Z","caller":"traceutil/trace.go:171","msg":"trace[1082261451] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kt6f9; range_end:; response_count:1; response_revision:490; }","duration":"172.805718ms","start":"2026-02-24T13:29:53.88107Z","end":"2026-02-24T13:29:54.053876Z","steps":["trace[1082261451] 'range keys from in-memory index tree' (duration: 172.475561ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:29:54.202691Z","caller":"traceutil/trace.go:171","msg":"trace[1212836448] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"131.367152ms","start":"2026-02-24T13:29:54.071301Z","end":"2026-02-24T13:29:54.202668Z","steps":["trace[1212836448] 'process raft request' (duration: 131.208408ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:29:56.205729Z","caller":"traceutil/trace.go:171","msg":"trace[367042720] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"435.567823ms","start":"2026-02-24T13:29:55.77012Z","end":"2026-02-24T13:29:56.205688Z","steps":["trace[367042720] 'read index received' (duration: 434.922177ms)","trace[367042720] 'applied index is now lower than readState.Index' (duration: 643.326µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:29:56.205844Z","caller":"traceutil/trace.go:171","msg":"trace[1114717029] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"585.815977ms","start":"2026-02-24T13:29:55.620001Z","end":"2026-02-24T13:29:56.205817Z","steps":["trace[1114717029] 'process raft request' (duration: 585.396047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:29:56.206012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.877861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T13:29:56.20607Z","caller":"traceutil/trace.go:171","msg":"trace[1784241510] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"436.011803ms","start":"2026-02-24T13:29:55.770042Z","end":"2026-02-24T13:29:56.206054Z","steps":["trace[1784241510] 'agreement among raft nodes before linearized reading' (duration: 435.839589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:29:56.206116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:29:55.770011Z","time spent":"436.095406ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T13:29:56.206333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.073473ms","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-02-24T13:29:56.206395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.652288ms","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-02-24T13:29:56.206568Z","caller":"traceutil/trace.go:171","msg":"trace[865148176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:492; }","duration":"405.875684ms","start":"2026-02-24T13:29:55.800656Z","end":"2026-02-24T13:29:56.206532Z","steps":["trace[865148176] 'agreement among raft nodes before linearized reading' (duration: 405.559146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:29:56.206633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:29:55.800639Z","time spent":"405.982937ms","remote":"127.0.0.1:53514","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-02-24T13:29:56.206475Z","caller":"traceutil/trace.go:171","msg":"trace[451962706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:492; }","duration":"418.268668ms","start":"2026-02-24T13:29:55.788179Z","end":"2026-02-24T13:29:56.206448Z","steps":["trace[451962706] 'agreement among raft nodes before linearized reading' (duration: 417.993481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:29:56.206704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:29:55.619985Z","time spent":"585.92728ms","remote":"127.0.0.1:53514","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-02-24T13:29:56.206761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:29:55.788152Z","time spent":"418.587705ms","remote":"127.0.0.1:53514","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-02-24T13:29:56.738311Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762926468065877,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T13:29:56.846034Z","caller":"traceutil/trace.go:171","msg":"trace[1525365614] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"614.760847ms","start":"2026-02-24T13:29:56.231156Z","end":"2026-02-24T13:29:56.845917Z","steps":["trace[1525365614] 'process raft request' (duration: 614.559981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:29:56.8464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:29:56.231133Z","time spent":"614.994322ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:29:56.847705Z","caller":"traceutil/trace.go:171","msg":"trace[966422363] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:511; }","duration":"609.791425ms","start":"2026-02-24T13:29:56.237891Z","end":"2026-02-24T13:29:56.847682Z","steps":["trace[966422363] 'read index received' (duration: 607.830317ms)","trace[966422363] 'applied index is now lower than readState.Index' (duration: 1.959428ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:29:56.847754Z","caller":"traceutil/trace.go:171","msg":"trace[1682183350] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"616.248794ms","start":"2026-02-24T13:29:56.231463Z","end":"2026-02-24T13:29:56.847712Z","steps":["trace[1682183350] 'process raft request' (duration: 616.039529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:29:56.847827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.921268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:29:56.847861Z","caller":"traceutil/trace.go:171","msg":"trace[2005669639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"609.99405ms","start":"2026-02-24T13:29:56.237857Z","end":"2026-02-24T13:29:56.847851Z","steps":["trace[2005669639] 'agreement among raft nodes before linearized reading' (duration: 609.919838ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:29:56.847844Z","caller":"traceutil/trace.go:171","msg":"trace[1470978260] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"616.283424ms","start":"2026-02-24T13:29:56.231525Z","end":"2026-02-24T13:29:56.847808Z","steps":["trace[1470978260] 'process raft request' (duration: 616.10556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:29:56.847903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:29:56.237843Z","time spent":"610.051791ms","remote":"127.0.0.1:53250","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-02-24T13:29:56.84791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:29:56.231444Z","time spent":"616.382567ms","remote":"127.0.0.1:53514","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-02-24T13:29:56.848019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:29:56.231495Z","time spent":"616.431748ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:29:59.602669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.120939ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-24T13:29:59.602952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:29:59.045243Z","time spent":"557.700957ms","remote":"127.0.0.1:53282","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T13:29:59.602988Z","caller":"traceutil/trace.go:171","msg":"trace[1408476779] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:530; }","duration":"376.996166ms","start":"2026-02-24T13:29:59.225969Z","end":"2026-02-24T13:29:59.602966Z","steps":["trace[1408476779] 'read index received' (duration: 149.219599ms)","trace[1408476779] 'applied index is now lower than readState.Index' (duration: 227.775177ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:29:59.603055Z","caller":"traceutil/trace.go:171","msg":"trace[1783502010] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"556.341724ms","start":"2026-02-24T13:29:59.046676Z","end":"2026-02-24T13:29:59.603018Z","steps":["trace[1783502010] 'process raft request' (duration: 556.187171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:29:59.603175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.195771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:29:59.603216Z","caller":"traceutil/trace.go:171","msg":"trace[1179360807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"377.286993ms","start":"2026-02-24T13:29:59.225916Z","end":"2026-02-24T13:29:59.603203Z","steps":["trace[1179360807] 'agreement among raft nodes before linearized reading' (duration: 377.18965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:29:59.60324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:29:59.225895Z","time spent":"377.340585ms","remote":"127.0.0.1:53250","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-02-24T13:29:59.603295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:29:59.046616Z","time spent":"556.556459ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T13:30:15.229134Z","caller":"traceutil/trace.go:171","msg":"trace[1061187966] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"281.479574ms","start":"2026-02-24T13:30:14.947634Z","end":"2026-02-24T13:30:15.229114Z","steps":["trace[1061187966] 'read index received' (duration: 281.29096ms)","trace[1061187966] 'applied index is now lower than readState.Index' (duration: 187.954µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:30:15.229232Z","caller":"traceutil/trace.go:171","msg":"trace[613123954] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"281.641388ms","start":"2026-02-24T13:30:14.947581Z","end":"2026-02-24T13:30:15.229223Z","steps":["trace[613123954] 'process raft request' (duration: 281.400452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:30:15.229384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.513234ms","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-02-24T13:30:15.229442Z","caller":"traceutil/trace.go:171","msg":"trace[1709226309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:675; }","duration":"281.611597ms","start":"2026-02-24T13:30:14.947814Z","end":"2026-02-24T13:30:15.229426Z","steps":["trace[1709226309] 'agreement among raft nodes before linearized reading' (duration: 281.478354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:30:15.229574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.895224ms","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-02-24T13:30:15.229677Z","caller":"traceutil/trace.go:171","msg":"trace[785962413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:675; }","duration":"282.047518ms","start":"2026-02-24T13:30:14.9476Z","end":"2026-02-24T13:30:15.229648Z","steps":["trace[785962413] 'agreement among raft nodes before linearized reading' (duration: 281.69127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:30:16.229252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.17463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:30:16.229414Z","caller":"traceutil/trace.go:171","msg":"trace[448155552] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:699; }","duration":"533.862583ms","start":"2026-02-24T13:30:15.695535Z","end":"2026-02-24T13:30:16.229398Z","steps":["trace[448155552] 'read index received' (duration: 304.55242ms)","trace[448155552] 'applied index is now lower than readState.Index' (duration: 229.309293ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:30:16.229625Z","caller":"traceutil/trace.go:171","msg":"trace[1821788030] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"997.005531ms","start":"2026-02-24T13:30:15.232588Z","end":"2026-02-24T13:30:16.229593Z","steps":["trace[1821788030] 'process raft request' (duration: 354.401471ms)","trace[1821788030] 'compare' (duration: 642.057957ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:30:16.229636Z","caller":"traceutil/trace.go:171","msg":"trace[573546879] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"995.282119ms","start":"2026-02-24T13:30:15.234328Z","end":"2026-02-24T13:30:16.22961Z","steps":["trace[573546879] 'process raft request' (duration: 995.024302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:30:16.229688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.15334ms","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-02-24T13:30:16.229723Z","caller":"traceutil/trace.go:171","msg":"trace[569752950] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:677; }","duration":"534.224392ms","start":"2026-02-24T13:30:15.695488Z","end":"2026-02-24T13:30:16.229712Z","steps":["trace[569752950] 'agreement among raft nodes before linearized reading' (duration: 533.961396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:30:16.229749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:30:15.23256Z","time spent":"997.120234ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:30:16.229753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:30:15.234299Z","time spent":"995.389491ms","remote":"127.0.0.1:53514","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-02-24T13:30:16.229755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:30:15.695467Z","time spent":"534.279713ms","remote":"127.0.0.1:53514","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-02-24T13:30:16.454576Z","caller":"traceutil/trace.go:171","msg":"trace[2128899349] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"130.347686ms","start":"2026-02-24T13:30:16.324205Z","end":"2026-02-24T13:30:16.454553Z","steps":["trace[2128899349] 'read index received' (duration: 77.736136ms)","trace[2128899349] 'applied index is now lower than readState.Index' (duration: 52.61054ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:30:16.454619Z","caller":"traceutil/trace.go:171","msg":"trace[1705228345] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"220.076787ms","start":"2026-02-24T13:30:16.234498Z","end":"2026-02-24T13:30:16.454575Z","steps":["trace[1705228345] 'process raft request' (duration: 167.459116ms)","trace[1705228345] 'compare' (duration: 52.490388ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:30:16.454797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.587443ms","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-02-24T13:30:16.454937Z","caller":"traceutil/trace.go:171","msg":"trace[536667771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:678; }","duration":"130.786327ms","start":"2026-02-24T13:30:16.324139Z","end":"2026-02-24T13:30:16.454925Z","steps":["trace[536667771] 'agreement among raft nodes before linearized reading' (duration: 130.49951ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:30:17.733106Z","caller":"traceutil/trace.go:171","msg":"trace[1273196191] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"237.091334ms","start":"2026-02-24T13:30:17.495996Z","end":"2026-02-24T13:30:17.733087Z","steps":["trace[1273196191] 'read index received' (duration: 237.010133ms)","trace[1273196191] 'applied index is now lower than readState.Index' (duration: 80.531µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:30:17.733197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.196178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:30:17.733219Z","caller":"traceutil/trace.go:171","msg":"trace[1323812402] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:680; }","duration":"237.279819ms","start":"2026-02-24T13:30:17.495932Z","end":"2026-02-24T13:30:17.733212Z","steps":["trace[1323812402] 'agreement among raft nodes before linearized reading' (duration: 237.223428ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:30:17.733197Z","caller":"traceutil/trace.go:171","msg":"trace[689741494] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"496.276861ms","start":"2026-02-24T13:30:17.236886Z","end":"2026-02-24T13:30:17.733162Z","steps":["trace[689741494] 'process raft request' (duration: 496.086366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:30:17.733361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:30:17.236856Z","time spent":"496.419945ms","remote":"127.0.0.1:53514","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-02-24T13:30:41.222406Z","caller":"traceutil/trace.go:171","msg":"trace[181498155] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"616.32584ms","start":"2026-02-24T13:30:40.606035Z","end":"2026-02-24T13:30:41.22236Z","steps":["trace[181498155] 'process raft request' (duration: 601.178294ms)","trace[181498155] 'compare' (duration: 14.497152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:30:41.2227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:30:40.606019Z","time spent":"616.563507ms","remote":"127.0.0.1:53514","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-02-24T13:30:42.841263Z","caller":"traceutil/trace.go:171","msg":"trace[1289971875] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"224.087087ms","start":"2026-02-24T13:30:42.617146Z","end":"2026-02-24T13:30:42.841233Z","steps":["trace[1289971875] 'process raft request' (duration: 200.29866ms)","trace[1289971875] 'compare' (duration: 23.625113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:30:43.190147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.039203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:30:43.190248Z","caller":"traceutil/trace.go:171","msg":"trace[748878659] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"221.836086ms","start":"2026-02-24T13:30:42.968395Z","end":"2026-02-24T13:30:43.190231Z","steps":["trace[748878659] 'process raft request' (duration: 114.632121ms)","trace[748878659] 'compare' (duration: 106.909149ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:30:43.409957Z","caller":"traceutil/trace.go:171","msg":"trace[1121399208] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"178.26279ms","start":"2026-02-24T13:30:43.231662Z","end":"2026-02-24T13:30:43.409925Z","steps":["trace[1121399208] 'process raft request' (duration: 174.516087ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:30:55.08355Z","caller":"traceutil/trace.go:171","msg":"trace[770901135] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"181.137286ms","start":"2026-02-24T13:30:54.902396Z","end":"2026-02-24T13:30:55.083533Z","steps":["trace[770901135] 'process raft request' (duration: 181.034394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:30:57.725533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762926468066675,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T13:30:57.988431Z","caller":"traceutil/trace.go:171","msg":"trace[993602305] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:882; }","duration":"763.120704ms","start":"2026-02-24T13:30:57.225285Z","end":"2026-02-24T13:30:57.988405Z","steps":["trace[993602305] 'read index received' (duration: 762.978981ms)","trace[993602305] 'applied index is now lower than readState.Index' (duration: 140.563µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:30:57.988775Z","caller":"traceutil/trace.go:171","msg":"trace[351267656] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"897.604291ms","start":"2026-02-24T13:30:57.091159Z","end":"2026-02-24T13:30:57.988764Z","steps":["trace[351267656] 'process raft request' (duration: 897.10435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:30:57.988959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:30:57.091139Z","time spent":"897.671443ms","remote":"127.0.0.1:53514","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-02-24T13:30:57.98898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.682187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:30:57.989055Z","caller":"traceutil/trace.go:171","msg":"trace[475228526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:848; }","duration":"763.796889ms","start":"2026-02-24T13:30:57.225239Z","end":"2026-02-24T13:30:57.989035Z","steps":["trace[475228526] 'agreement among raft nodes before linearized reading' (duration: 763.688277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:30:57.989049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.019244ms","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-02-24T13:30:57.989092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:30:57.225214Z","time spent":"763.870432ms","remote":"127.0.0.1:53250","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-02-24T13:30:57.989103Z","caller":"traceutil/trace.go:171","msg":"trace[1220736146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:848; }","duration":"501.109836ms","start":"2026-02-24T13:30:57.487978Z","end":"2026-02-24T13:30:57.989088Z","steps":["trace[1220736146] 'agreement among raft nodes before linearized reading' (duration: 500.884161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:30:57.989136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:30:57.487961Z","time spent":"501.166547ms","remote":"127.0.0.1:53514","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-02-24T13:30:58.462681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.572554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:30:58.462758Z","caller":"traceutil/trace.go:171","msg":"trace[1439467674] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"469.242926ms","start":"2026-02-24T13:30:57.993497Z","end":"2026-02-24T13:30:58.46274Z","steps":["trace[1439467674] 'process raft request' (duration: 91.558621ms)","trace[1439467674] 'compare' (duration: 377.396079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:30:58.462789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:30:57.993448Z","time spent":"469.327218ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:30:58.633442Z","caller":"traceutil/trace.go:171","msg":"trace[1637420167] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"639.567502ms","start":"2026-02-24T13:30:57.993853Z","end":"2026-02-24T13:30:58.63342Z","steps":["trace[1637420167] 'process raft request' (duration: 587.820198ms)","trace[1637420167] 'compare' (duration: 51.305803ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:30:58.633678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:30:57.993837Z","time spent":"639.775436ms","remote":"127.0.0.1:53514","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-02-24T13:31:12.384184Z","caller":"traceutil/trace.go:171","msg":"trace[1262161907] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"159.118796ms","start":"2026-02-24T13:31:12.225045Z","end":"2026-02-24T13:31:12.384164Z","steps":["trace[1262161907] 'read index received' (duration: 159.012133ms)","trace[1262161907] 'applied index is now lower than readState.Index' (duration: 105.863µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:31:12.3843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.244068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:31:12.384327Z","caller":"traceutil/trace.go:171","msg":"trace[1692269736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"159.31854ms","start":"2026-02-24T13:31:12.224998Z","end":"2026-02-24T13:31:12.384316Z","steps":["trace[1692269736] 'agreement among raft nodes before linearized reading' (duration: 159.245528ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:31:12.384336Z","caller":"traceutil/trace.go:171","msg":"trace[1133447715] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"339.791951ms","start":"2026-02-24T13:31:12.044506Z","end":"2026-02-24T13:31:12.384298Z","steps":["trace[1133447715] 'process raft request' (duration: 339.541725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:12.384512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:12.044492Z","time spent":"339.928934ms","remote":"127.0.0.1:53514","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-02-24T13:31:13.567524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.292298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:31:13.567635Z","caller":"traceutil/trace.go:171","msg":"trace[1859005923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"342.455911ms","start":"2026-02-24T13:31:13.225157Z","end":"2026-02-24T13:31:13.567613Z","steps":["trace[1859005923] 'range keys from in-memory index tree' (duration: 342.208516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:13.567669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031138236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T13:31:13.5677Z","caller":"traceutil/trace.go:171","msg":"trace[402763847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:888; }","duration":"1.031161447s","start":"2026-02-24T13:31:12.536524Z","end":"2026-02-24T13:31:13.567686Z","steps":["trace[402763847] 'range keys from in-memory index tree' (duration: 1.031004843s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:13.567701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:13.225136Z","time spent":"342.554684ms","remote":"127.0.0.1:53250","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-02-24T13:31:13.567723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:12.536465Z","time spent":"1.031253608s","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T13:31:13.567733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.623806ms","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-02-24T13:31:13.567804Z","caller":"traceutil/trace.go:171","msg":"trace[607209136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:888; }","duration":"864.737598ms","start":"2026-02-24T13:31:12.703048Z","end":"2026-02-24T13:31:13.567785Z","steps":["trace[607209136] 'range keys from in-memory index tree' (duration: 864.462722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:13.567844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:12.703029Z","time spent":"864.806ms","remote":"127.0.0.1:53514","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-02-24T13:31:13.567512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077626102s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T13:31:13.567984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.685877ms","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-02-24T13:31:13.568002Z","caller":"traceutil/trace.go:171","msg":"trace[194020024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:888; }","duration":"855.728907ms","start":"2026-02-24T13:31:12.712268Z","end":"2026-02-24T13:31:13.567996Z","steps":["trace[194020024] 'range keys from in-memory index tree' (duration: 855.619105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:13.568019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:12.712255Z","time spent":"855.759389ms","remote":"127.0.0.1:53514","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-02-24T13:31:13.56802Z","caller":"traceutil/trace.go:171","msg":"trace[565311202] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:888; }","duration":"1.078124143s","start":"2026-02-24T13:31:12.489861Z","end":"2026-02-24T13:31:13.567985Z","steps":["trace[565311202] 'range keys from in-memory index tree' (duration: 1.077611992s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:13.568293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.594655ms","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-02-24T13:31:13.568409Z","caller":"traceutil/trace.go:171","msg":"trace[379076415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:888; }","duration":"855.750818ms","start":"2026-02-24T13:31:12.712632Z","end":"2026-02-24T13:31:13.568383Z","steps":["trace[379076415] 'range keys from in-memory index tree' (duration: 855.39007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:13.56847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:12.712626Z","time spent":"855.828389ms","remote":"127.0.0.1:53514","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-02-24T13:31:13.697076Z","caller":"traceutil/trace.go:171","msg":"trace[1392570360] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"122.49019ms","start":"2026-02-24T13:31:13.574563Z","end":"2026-02-24T13:31:13.697053Z","steps":["trace[1392570360] 'process raft request' (duration: 122.375587ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:31:13.69731Z","caller":"traceutil/trace.go:171","msg":"trace[1466099867] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"123.160024ms","start":"2026-02-24T13:31:13.574141Z","end":"2026-02-24T13:31:13.697301Z","steps":["trace[1466099867] 'process raft request' (duration: 105.790377ms)","trace[1466099867] 'compare' (duration: 16.792555ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:31:13.697387Z","caller":"traceutil/trace.go:171","msg":"trace[1577917510] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"121.817455ms","start":"2026-02-24T13:31:13.575518Z","end":"2026-02-24T13:31:13.697336Z","steps":["trace[1577917510] 'process raft request' (duration: 121.501008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:14.722552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.963104ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T13:31:14.722662Z","caller":"traceutil/trace.go:171","msg":"trace[1758338723] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"496.265338ms","start":"2026-02-24T13:31:14.226375Z","end":"2026-02-24T13:31:14.722641Z","steps":["trace[1758338723] 'read index received' (duration: 16.11392ms)","trace[1758338723] 'applied index is now lower than readState.Index' (duration: 480.149848ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:31:14.72361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.206389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:31:14.723777Z","caller":"traceutil/trace.go:171","msg":"trace[2039434275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"497.396032ms","start":"2026-02-24T13:31:14.226339Z","end":"2026-02-24T13:31:14.723735Z","steps":["trace[2039434275] 'agreement among raft nodes before linearized reading' (duration: 497.191008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:14.723946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:14.226323Z","time spent":"497.608938ms","remote":"127.0.0.1:53250","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-02-24T13:31:14.724256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.12149ms","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-02-24T13:31:14.724356Z","caller":"traceutil/trace.go:171","msg":"trace[1870217753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"336.256172ms","start":"2026-02-24T13:31:14.388078Z","end":"2026-02-24T13:31:14.724334Z","steps":["trace[1870217753] 'agreement among raft nodes before linearized reading' (duration: 335.399043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:14.724401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:14.388063Z","time spent":"336.328215ms","remote":"127.0.0.1:53514","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-02-24T13:31:15.221646Z","caller":"traceutil/trace.go:171","msg":"trace[1790574305] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"492.209737ms","start":"2026-02-24T13:31:14.729378Z","end":"2026-02-24T13:31:15.221588Z","steps":["trace[1790574305] 'process raft request' (duration: 491.946372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:15.22179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:14.729322Z","time spent":"492.375701ms","remote":"127.0.0.1:53514","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-02-24T13:31:16.050003Z","caller":"traceutil/trace.go:171","msg":"trace[1419979117] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"374.657938ms","start":"2026-02-24T13:31:15.67532Z","end":"2026-02-24T13:31:16.049977Z","steps":["trace[1419979117] 'process raft request' (duration: 374.484165ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:31:16.050113Z","caller":"traceutil/trace.go:171","msg":"trace[1931650116] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:935; }","duration":"349.511509ms","start":"2026-02-24T13:31:15.700574Z","end":"2026-02-24T13:31:16.050085Z","steps":["trace[1931650116] 'read index received' (duration: 349.503639ms)","trace[1931650116] 'applied index is now lower than readState.Index' (duration: 5.86µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:31:16.050291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:15.6753Z","time spent":"374.891703ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:31:16.050369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.358155ms","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-02-24T13:31:16.050409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.810745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T13:31:16.050414Z","caller":"traceutil/trace.go:171","msg":"trace[248525086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"349.423377ms","start":"2026-02-24T13:31:15.700977Z","end":"2026-02-24T13:31:16.0504Z","steps":["trace[248525086] 'agreement among raft nodes before linearized reading' (duration: 349.291954ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:31:16.050446Z","caller":"traceutil/trace.go:171","msg":"trace[1315280226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"349.913617ms","start":"2026-02-24T13:31:15.700522Z","end":"2026-02-24T13:31:16.050435Z","steps":["trace[1315280226] 'agreement among raft nodes before linearized reading' (duration: 349.710663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:16.050459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:15.700968Z","time spent":"349.481108ms","remote":"127.0.0.1:53514","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-02-24T13:31:16.050482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:15.700511Z","time spent":"349.963208ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T13:31:16.050488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.421087ms","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-02-24T13:31:16.05057Z","caller":"traceutil/trace.go:171","msg":"trace[1561639441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"349.531039ms","start":"2026-02-24T13:31:15.701018Z","end":"2026-02-24T13:31:16.050549Z","steps":["trace[1561639441] 'agreement among raft nodes before linearized reading' (duration: 349.268843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:16.050614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:15.701013Z","time spent":"349.59293ms","remote":"127.0.0.1:53514","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-02-24T13:31:16.498623Z","caller":"traceutil/trace.go:171","msg":"trace[747048624] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"127.784018ms","start":"2026-02-24T13:31:16.370816Z","end":"2026-02-24T13:31:16.4986Z","steps":["trace[747048624] 'process raft request' (duration: 127.636214ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:31:17.417369Z","caller":"traceutil/trace.go:171","msg":"trace[397066990] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"183.947659ms","start":"2026-02-24T13:31:17.233401Z","end":"2026-02-24T13:31:17.417348Z","steps":["trace[397066990] 'process raft request' (duration: 183.807576ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:31:18.164228Z","caller":"traceutil/trace.go:171","msg":"trace[1980347874] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"102.982084ms","start":"2026-02-24T13:31:18.061222Z","end":"2026-02-24T13:31:18.164204Z","steps":["trace[1980347874] 'process raft request' (duration: 102.609476ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:31:18.30092Z","caller":"traceutil/trace.go:171","msg":"trace[1377201770] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"131.95388ms","start":"2026-02-24T13:31:18.168941Z","end":"2026-02-24T13:31:18.300894Z","steps":["trace[1377201770] 'process raft request' (duration: 122.741145ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:31:18.301056Z","caller":"traceutil/trace.go:171","msg":"trace[2087051754] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"131.336526ms","start":"2026-02-24T13:31:18.169709Z","end":"2026-02-24T13:31:18.301045Z","steps":["trace[2087051754] 'process raft request' (duration: 131.110491ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:31:25.954329Z","caller":"traceutil/trace.go:171","msg":"trace[636408241] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"341.936699ms","start":"2026-02-24T13:31:25.61237Z","end":"2026-02-24T13:31:25.954307Z","steps":["trace[636408241] 'read index received' (duration: 341.716134ms)","trace[636408241] 'applied index is now lower than readState.Index' (duration: 219.925µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:31:25.95438Z","caller":"traceutil/trace.go:171","msg":"trace[254572058] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"491.828408ms","start":"2026-02-24T13:31:25.462521Z","end":"2026-02-24T13:31:25.954349Z","steps":["trace[254572058] 'process raft request' (duration: 491.666084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:25.954468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.072262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:31:25.954503Z","caller":"traceutil/trace.go:171","msg":"trace[1586483045] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:950; }","duration":"342.127023ms","start":"2026-02-24T13:31:25.612365Z","end":"2026-02-24T13:31:25.954492Z","steps":["trace[1586483045] 'agreement among raft nodes before linearized reading' (duration: 342.041791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:25.954537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:25.612328Z","time spent":"342.198824ms","remote":"127.0.0.1:53356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-24T13:31:25.954539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:25.462501Z","time spent":"491.960462ms","remote":"127.0.0.1:53514","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-02-24T13:31:26.244261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.139095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:31:26.244369Z","caller":"traceutil/trace.go:171","msg":"trace[320264380] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:950; }","duration":"220.314709ms","start":"2026-02-24T13:31:26.024031Z","end":"2026-02-24T13:31:26.244346Z","steps":["trace[320264380] 'count revisions from in-memory index tree' (duration: 220.028663ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:31:30.49345Z","caller":"traceutil/trace.go:171","msg":"trace[434896118] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"139.11804ms","start":"2026-02-24T13:31:30.354308Z","end":"2026-02-24T13:31:30.493426Z","steps":["trace[434896118] 'read index received' (duration: 139.061978ms)","trace[434896118] 'applied index is now lower than readState.Index' (duration: 54.682µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:31:30.493544Z","caller":"traceutil/trace.go:171","msg":"trace[1194156216] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"139.433257ms","start":"2026-02-24T13:31:30.354081Z","end":"2026-02-24T13:31:30.493514Z","steps":["trace[1194156216] 'process raft request' (duration: 139.209632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:30.493704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.373846ms","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-02-24T13:31:30.493748Z","caller":"traceutil/trace.go:171","msg":"trace[1816967086] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:980; }","duration":"139.480677ms","start":"2026-02-24T13:31:30.354255Z","end":"2026-02-24T13:31:30.493736Z","steps":["trace[1816967086] 'agreement among raft nodes before linearized reading' (duration: 139.291353ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:31:31.794549Z","caller":"traceutil/trace.go:171","msg":"trace[630853899] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"300.948586ms","start":"2026-02-24T13:31:31.493576Z","end":"2026-02-24T13:31:31.794525Z","steps":["trace[630853899] 'process raft request' (duration: 298.46706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:31:31.794758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:31:31.493555Z","time spent":"301.14077ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T13:33:14.946307Z","caller":"traceutil/trace.go:171","msg":"trace[1794590867] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"119.927112ms","start":"2026-02-24T13:33:14.82636Z","end":"2026-02-24T13:33:14.946287Z","steps":["trace[1794590867] 'process raft request' (duration: 119.810649ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:33:21.281977Z","caller":"traceutil/trace.go:171","msg":"trace[37248720] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"121.369275ms","start":"2026-02-24T13:33:21.16059Z","end":"2026-02-24T13:33:21.28196Z","steps":["trace[37248720] 'process raft request' (duration: 26.859479ms)","trace[37248720] 'compare' (duration: 94.215009ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:34:29.313818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.182067ms","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-02-24T13:34:29.313918Z","caller":"traceutil/trace.go:171","msg":"trace[2085928499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1750; }","duration":"140.31204ms","start":"2026-02-24T13:34:29.173582Z","end":"2026-02-24T13:34:29.313894Z","steps":["trace[2085928499] 'range keys from in-memory index tree' (duration: 140.013084ms)"],"step_count":1}