{"level":"warn","ts":"2026-01-30T09:35:52.997059Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T09:35:52.997247Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.140: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.140:2380","--initial-cluster=instance=https://162.253.55.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.140: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-01-30T09:35:52.997324Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T09:35:52.997333Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-01-30T09:35:52.997352Z","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-01-30T09:35:52.998044Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"]} {"level":"info","ts":"2026-01-30T09:35:52.998135Z","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":2,"max-cpu-available":2,"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.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.140: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-01-30T09:35:53.002604Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.222463ms"} {"level":"info","ts":"2026-01-30T09:35:53.01054Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf809824ab95ac81","cluster-id":"6f89f5fbef18dc61"} {"level":"info","ts":"2026-01-30T09:35:53.01058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=()"} {"level":"info","ts":"2026-01-30T09:35:53.01061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 0"} {"level":"info","ts":"2026-01-30T09:35:53.010622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf809824ab95ac81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-30T09:35:53.01063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 1"} {"level":"info","ts":"2026-01-30T09:35:53.01067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"warn","ts":"2026-01-30T09:35:53.016312Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-30T09:35:53.033586Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-30T09:35:53.041612Z","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-01-30T09:35:53.044253Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf809824ab95ac81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-30T09:35:53.045931Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf809824ab95ac81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-30T09:35:53.046168Z","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-01-30T09:35:53.046197Z","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-01-30T09:35:53.046209Z","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-01-30T09:35:53.046519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"info","ts":"2026-01-30T09:35:53.046658Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","added-peer-id":"cf809824ab95ac81","added-peer-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-01-30T09:35:53.049473Z","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-01-30T09:35:53.050333Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-01-30T09:35:53.050349Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-01-30T09:35:53.051427Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf809824ab95ac81","initial-advertise-peer-urls":["https://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-30T09:35:53.051452Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-30T09:35:53.411328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 is starting a new election at term 1"} {"level":"info","ts":"2026-01-30T09:35:53.411376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-30T09:35:53.411412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgPreVoteResp from cf809824ab95ac81 at term 1"} {"level":"info","ts":"2026-01-30T09:35:53.411432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became candidate at term 2"} {"level":"info","ts":"2026-01-30T09:35:53.411438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgVoteResp from cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-01-30T09:35:53.411447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became leader at term 2"} {"level":"info","ts":"2026-01-30T09:35:53.411455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf809824ab95ac81 elected leader cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-01-30T09:35:53.41239Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T09:35:53.413261Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf809824ab95ac81","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.140:2379]}","request-path":"/0/members/cf809824ab95ac81/attributes","cluster-id":"6f89f5fbef18dc61","publish-timeout":"7s"} {"level":"info","ts":"2026-01-30T09:35:53.413284Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T09:35:53.413635Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T09:35:53.413695Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T09:35:53.413712Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T09:35:53.41372Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T09:35:53.417188Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.140:2379"} {"level":"info","ts":"2026-01-30T09:35:53.424419Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-30T09:35:53.428367Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-30T09:35:53.42846Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-30T09:36:12.446691Z","caller":"traceutil/trace.go:171","msg":"trace[351007600] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"139.565197ms","start":"2026-01-30T09:36:12.306911Z","end":"2026-01-30T09:36:12.446476Z","steps":["trace[351007600] 'process raft request' (duration: 139.378262ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:13.321533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.982937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T09:36:13.321876Z","caller":"traceutil/trace.go:171","msg":"trace[898492952] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"1.013772392s","start":"2026-01-30T09:36:12.308077Z","end":"2026-01-30T09:36:13.321849Z","steps":["trace[898492952] 'process raft request' (duration: 807.040385ms)","trace[898492952] 'compare' (duration: 205.864155ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:36:13.321999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:12.308044Z","time spent":"1.013889506s","remote":"127.0.0.1:52030","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-01-30T09:36:13.322179Z","caller":"traceutil/trace.go:171","msg":"trace[1141719825] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:421; }","duration":"974.464787ms","start":"2026-01-30T09:36:12.347708Z","end":"2026-01-30T09:36:13.322173Z","steps":["trace[1141719825] 'read index received' (duration: 98.66886ms)","trace[1141719825] 'applied index is now lower than readState.Index' (duration: 875.794797ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:36:13.323574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.862574ms","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-01-30T09:36:13.323606Z","caller":"traceutil/trace.go:171","msg":"trace[147261008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"975.946686ms","start":"2026-01-30T09:36:12.347652Z","end":"2026-01-30T09:36:13.323599Z","steps":["trace[147261008] 'agreement among raft nodes before linearized reading' (duration: 974.577519ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:13.323567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.009393ms","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-01-30T09:36:13.323623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:12.347625Z","time spent":"975.994757ms","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-30T09:36:13.323637Z","caller":"traceutil/trace.go:171","msg":"trace[2002853564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"465.126356ms","start":"2026-01-30T09:36:12.858496Z","end":"2026-01-30T09:36:13.323622Z","steps":["trace[2002853564] 'agreement among raft nodes before linearized reading' (duration: 465.024473ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:13.323677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:12.858466Z","time spent":"465.201608ms","remote":"127.0.0.1:52030","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-01-30T09:36:26.892993Z","caller":"traceutil/trace.go:171","msg":"trace[2018444503] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"109.751622ms","start":"2026-01-30T09:36:26.783006Z","end":"2026-01-30T09:36:26.892757Z","steps":["trace[2018444503] 'process raft request' (duration: 109.551937ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:36:27.602402Z","caller":"traceutil/trace.go:171","msg":"trace[1517362519] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"350.499776ms","start":"2026-01-30T09:36:27.251884Z","end":"2026-01-30T09:36:27.602384Z","steps":["trace[1517362519] 'process raft request' (duration: 350.400333ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:27.602501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:27.251867Z","time spent":"350.583907ms","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T09:36:27.602763Z","caller":"traceutil/trace.go:171","msg":"trace[1702146120] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:528; }","duration":"188.224589ms","start":"2026-01-30T09:36:27.414531Z","end":"2026-01-30T09:36:27.602755Z","steps":["trace[1702146120] 'read index received' (duration: 188.221879ms)","trace[1702146120] 'applied index is now lower than readState.Index' (duration: 2.37µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:36:27.602878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.343672ms","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-01-30T09:36:27.602894Z","caller":"traceutil/trace.go:171","msg":"trace[375732171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"188.398234ms","start":"2026-01-30T09:36:27.414492Z","end":"2026-01-30T09:36:27.60289Z","steps":["trace[375732171] 'agreement among raft nodes before linearized reading' (duration: 188.297861ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:36:27.969462Z","caller":"traceutil/trace.go:171","msg":"trace[809015661] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"487.781683ms","start":"2026-01-30T09:36:27.481664Z","end":"2026-01-30T09:36:27.969445Z","steps":["trace[809015661] 'process raft request' (duration: 487.603218ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:27.96955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:27.481639Z","time spent":"487.866005ms","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T09:36:27.970177Z","caller":"traceutil/trace.go:171","msg":"trace[1874629579] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:529; }","duration":"367.390321ms","start":"2026-01-30T09:36:27.602778Z","end":"2026-01-30T09:36:27.970168Z","steps":["trace[1874629579] 'read index received' (duration: 367.386851ms)","trace[1874629579] 'applied index is now lower than readState.Index' (duration: 2.85µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:36:27.970258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.883595ms","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-01-30T09:36:27.970274Z","caller":"traceutil/trace.go:171","msg":"trace[166295882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"553.914335ms","start":"2026-01-30T09:36:27.416355Z","end":"2026-01-30T09:36:27.97027Z","steps":["trace[166295882] 'agreement among raft nodes before linearized reading' (duration: 553.846163ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:27.97029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:27.416352Z","time spent":"553.935056ms","remote":"127.0.0.1:52030","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-01-30T09:36:27.970385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.041588ms","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-01-30T09:36:27.970398Z","caller":"traceutil/trace.go:171","msg":"trace[1923330712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"554.0675ms","start":"2026-01-30T09:36:27.416327Z","end":"2026-01-30T09:36:27.970394Z","steps":["trace[1923330712] 'agreement among raft nodes before linearized reading' (duration: 554.043399ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:27.97041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:27.41632Z","time spent":"554.08749ms","remote":"127.0.0.1:52030","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-01-30T09:36:27.973214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.000411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:36:27.973251Z","caller":"traceutil/trace.go:171","msg":"trace[343262401] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:516; }","duration":"323.071603ms","start":"2026-01-30T09:36:27.650172Z","end":"2026-01-30T09:36:27.973243Z","steps":["trace[343262401] 'agreement among raft nodes before linearized reading' (duration: 323.008171ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:27.973266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:27.650157Z","time spent":"323.105383ms","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-01-30T09:36:27.973369Z","caller":"traceutil/trace.go:171","msg":"trace[1535557848] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"364.756441ms","start":"2026-01-30T09:36:27.608607Z","end":"2026-01-30T09:36:27.973363Z","steps":["trace[1535557848] 'process raft request' (duration: 364.497474ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:27.973412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:27.608584Z","time spent":"364.797502ms","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T09:36:27.973537Z","caller":"traceutil/trace.go:171","msg":"trace[2111582568] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"364.184486ms","start":"2026-01-30T09:36:27.60934Z","end":"2026-01-30T09:36:27.973525Z","steps":["trace[2111582568] 'process raft request' (duration: 363.811706ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:27.973563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:27.609334Z","time spent":"364.214067ms","remote":"127.0.0.1:52030","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-01-30T09:36:27.973706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.978657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:36:27.973721Z","caller":"traceutil/trace.go:171","msg":"trace[315102483] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:516; }","duration":"124.022878ms","start":"2026-01-30T09:36:27.849693Z","end":"2026-01-30T09:36:27.973715Z","steps":["trace[315102483] 'agreement among raft nodes before linearized reading' (duration: 123.999887ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:27.97378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.429497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-30T09:36:27.973938Z","caller":"traceutil/trace.go:171","msg":"trace[643913262] range","detail":"{range_begin:/registry/masterleases/162.253.55.140; range_end:; response_count:1; response_revision:516; }","duration":"297.610412ms","start":"2026-01-30T09:36:27.676323Z","end":"2026-01-30T09:36:27.973933Z","steps":["trace[643913262] 'agreement among raft nodes before linearized reading' (duration: 297.442257ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:36:28.489757Z","caller":"traceutil/trace.go:171","msg":"trace[974746923] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"239.91813ms","start":"2026-01-30T09:36:28.249824Z","end":"2026-01-30T09:36:28.489742Z","steps":["trace[974746923] 'read index received' (duration: 239.455788ms)","trace[974746923] 'applied index is now lower than readState.Index' (duration: 461.722µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:36:28.490639Z","caller":"traceutil/trace.go:171","msg":"trace[1152268449] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"339.803483ms","start":"2026-01-30T09:36:28.1507Z","end":"2026-01-30T09:36:28.490504Z","steps":["trace[1152268449] 'process raft request' (duration: 338.740665ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:28.490754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:28.150678Z","time spent":"339.996119ms","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T09:36:28.491198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.376719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-01-30T09:36:28.491225Z","caller":"traceutil/trace.go:171","msg":"trace[103146089] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:522; }","duration":"241.449671ms","start":"2026-01-30T09:36:28.249763Z","end":"2026-01-30T09:36:28.491212Z","steps":["trace[103146089] 'agreement among raft nodes before linearized reading' (duration: 241.355928ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:29.235383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.143237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-01-30T09:36:29.235423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.353985ms","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-01-30T09:36:29.235458Z","caller":"traceutil/trace.go:171","msg":"trace[507680753] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:522; }","duration":"569.25443ms","start":"2026-01-30T09:36:28.666186Z","end":"2026-01-30T09:36:29.235441Z","steps":["trace[507680753] 'range keys from in-memory index tree' (duration: 568.980683ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:36:29.235489Z","caller":"traceutil/trace.go:171","msg":"trace[1557317249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"631.481959ms","start":"2026-01-30T09:36:28.603987Z","end":"2026-01-30T09:36:29.235469Z","steps":["trace[1557317249] 'range keys from in-memory index tree' (duration: 631.13861ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:29.235514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:28.666115Z","time spent":"569.378903ms","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-01-30T09:36:29.235523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:28.603971Z","time spent":"631.546581ms","remote":"127.0.0.1:52030","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":"info","ts":"2026-01-30T09:36:29.241387Z","caller":"traceutil/trace.go:171","msg":"trace[67290506] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"392.572534ms","start":"2026-01-30T09:36:28.848803Z","end":"2026-01-30T09:36:29.241376Z","steps":["trace[67290506] 'read index received' (duration: 392.195784ms)","trace[67290506] 'applied index is now lower than readState.Index' (duration: 375.95µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:36:29.242185Z","caller":"traceutil/trace.go:171","msg":"trace[1272491405] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"469.367537ms","start":"2026-01-30T09:36:28.772807Z","end":"2026-01-30T09:36:29.242175Z","steps":["trace[1272491405] 'process raft request' (duration: 468.32858ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:29.242293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:28.772785Z","time spent":"469.435049ms","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T09:36:29.24272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.920889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-01-30T09:36:29.242757Z","caller":"traceutil/trace.go:171","msg":"trace[1337767156] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:524; }","duration":"393.95791ms","start":"2026-01-30T09:36:28.848791Z","end":"2026-01-30T09:36:29.242749Z","steps":["trace[1337767156] 'agreement among raft nodes before linearized reading' (duration: 393.869798ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:29.242774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:28.848775Z","time spent":"393.995222ms","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-01-30T09:36:29.249818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.248154ms","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":"info","ts":"2026-01-30T09:36:29.249856Z","caller":"traceutil/trace.go:171","msg":"trace[1989009651] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:524; }","duration":"201.318705ms","start":"2026-01-30T09:36:29.04853Z","end":"2026-01-30T09:36:29.249849Z","steps":["trace[1989009651] 'agreement among raft nodes before linearized reading' (duration: 194.33008ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:30.273056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.378255ms","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-01-30T09:36:30.273114Z","caller":"traceutil/trace.go:171","msg":"trace[1194021632] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:528; }","duration":"404.464558ms","start":"2026-01-30T09:36:29.868636Z","end":"2026-01-30T09:36:30.2731Z","steps":["trace[1194021632] 'range keys from in-memory index tree' (duration: 404.158109ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:30.273144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:29.86862Z","time spent":"404.519079ms","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-01-30T09:36:30.273574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.34469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T09:36:30.273623Z","caller":"traceutil/trace.go:171","msg":"trace[1826631743] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"274.285497ms","start":"2026-01-30T09:36:29.999329Z","end":"2026-01-30T09:36:30.273615Z","steps":["trace[1826631743] 'read index received' (duration: 128.723592ms)","trace[1826631743] 'applied index is now lower than readState.Index' (duration: 145.561205ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:36:30.274006Z","caller":"traceutil/trace.go:171","msg":"trace[73544934] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"405.052113ms","start":"2026-01-30T09:36:29.868944Z","end":"2026-01-30T09:36:30.273996Z","steps":["trace[73544934] 'process raft request' (duration: 259.235281ms)","trace[73544934] 'compare' (duration: 145.190616ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:36:30.274051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:29.868932Z","time spent":"405.098354ms","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T09:36:30.274126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.80244ms","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-01-30T09:36:30.274146Z","caller":"traceutil/trace.go:171","msg":"trace[1423625495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"274.850572ms","start":"2026-01-30T09:36:29.99929Z","end":"2026-01-30T09:36:30.274141Z","steps":["trace[1423625495] 'agreement among raft nodes before linearized reading' (duration: 274.810251ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:30.27427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.163341ms","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-01-30T09:36:30.274296Z","caller":"traceutil/trace.go:171","msg":"trace[1285209179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"239.226893ms","start":"2026-01-30T09:36:30.035061Z","end":"2026-01-30T09:36:30.274288Z","steps":["trace[1285209179] 'agreement among raft nodes before linearized reading' (duration: 239.185502ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:30.274649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.963989ms","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-01-30T09:36:30.274673Z","caller":"traceutil/trace.go:171","msg":"trace[932516448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"211.00736ms","start":"2026-01-30T09:36:30.06366Z","end":"2026-01-30T09:36:30.274667Z","steps":["trace[932516448] 'agreement among raft nodes before linearized reading' (duration: 210.927278ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:30.274764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.672103ms","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-01-30T09:36:30.274785Z","caller":"traceutil/trace.go:171","msg":"trace[135823999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:529; }","duration":"223.707485ms","start":"2026-01-30T09:36:30.051072Z","end":"2026-01-30T09:36:30.27478Z","steps":["trace[135823999] 'agreement among raft nodes before linearized reading' (duration: 223.672074ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:30.274981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.875942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-01-30T09:36:30.275003Z","caller":"traceutil/trace.go:171","msg":"trace[480796538] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:529; }","duration":"225.924793ms","start":"2026-01-30T09:36:30.049073Z","end":"2026-01-30T09:36:30.274998Z","steps":["trace[480796538] 'agreement among raft nodes before linearized reading' (duration: 225.780289ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:30.634753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.596082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T09:36:30.635077Z","caller":"traceutil/trace.go:171","msg":"trace[1815866751] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:545; }","duration":"185.092277ms","start":"2026-01-30T09:36:30.449972Z","end":"2026-01-30T09:36:30.635065Z","steps":["trace[1815866751] 'read index received' (duration: 182.878809ms)","trace[1815866751] 'applied index is now lower than readState.Index' (duration: 2.212978ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:36:30.635213Z","caller":"traceutil/trace.go:171","msg":"trace[1583341838] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"319.446147ms","start":"2026-01-30T09:36:30.315759Z","end":"2026-01-30T09:36:30.635205Z","steps":["trace[1583341838] 'process raft request' (duration: 84.328462ms)","trace[1583341838] 'compare' (duration: 234.502119ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:36:30.635246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:30.315737Z","time spent":"319.490768ms","remote":"127.0.0.1:52030","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-01-30T09:36:30.635337Z","caller":"traceutil/trace.go:171","msg":"trace[444001284] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"319.117498ms","start":"2026-01-30T09:36:30.316215Z","end":"2026-01-30T09:36:30.635333Z","steps":["trace[444001284] 'process raft request' (duration: 318.615655ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:30.635359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:30.316204Z","time spent":"319.14261ms","remote":"127.0.0.1:52030","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-01-30T09:36:30.635411Z","caller":"traceutil/trace.go:171","msg":"trace[1924144957] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"319.124268ms","start":"2026-01-30T09:36:30.316282Z","end":"2026-01-30T09:36:30.635407Z","steps":["trace[1924144957] 'process raft request' (duration: 318.595894ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:30.635433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:30.316267Z","time spent":"319.15358ms","remote":"127.0.0.1:52030","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-01-30T09:36:30.63552Z","caller":"traceutil/trace.go:171","msg":"trace[2032476357] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"298.806973ms","start":"2026-01-30T09:36:30.336706Z","end":"2026-01-30T09:36:30.635513Z","steps":["trace[2032476357] 'process raft request' (duration: 298.190927ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:36:30.635573Z","caller":"traceutil/trace.go:171","msg":"trace[564832103] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"287.724871ms","start":"2026-01-30T09:36:30.347845Z","end":"2026-01-30T09:36:30.63557Z","steps":["trace[564832103] 'process raft request' (duration: 287.069754ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:36:30.636292Z","caller":"traceutil/trace.go:171","msg":"trace[56335853] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"251.690152ms","start":"2026-01-30T09:36:30.384595Z","end":"2026-01-30T09:36:30.636285Z","steps":["trace[56335853] 'process raft request' (duration: 250.380958ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:36:30.636513Z","caller":"traceutil/trace.go:171","msg":"trace[800283616] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"248.490218ms","start":"2026-01-30T09:36:30.388018Z","end":"2026-01-30T09:36:30.636508Z","steps":["trace[800283616] 'process raft request' (duration: 247.02948ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:30.636692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.71542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-01-30T09:36:30.636711Z","caller":"traceutil/trace.go:171","msg":"trace[444999359] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:536; }","duration":"186.761741ms","start":"2026-01-30T09:36:30.449941Z","end":"2026-01-30T09:36:30.636703Z","steps":["trace[444999359] 'agreement among raft nodes before linearized reading' (duration: 186.665189ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:30.636837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.218144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:36:30.636851Z","caller":"traceutil/trace.go:171","msg":"trace[1699545414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"165.242264ms","start":"2026-01-30T09:36:30.471604Z","end":"2026-01-30T09:36:30.636847Z","steps":["trace[1699545414] 'agreement among raft nodes before linearized reading' (duration: 165.209043ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:36:35.532817Z","caller":"traceutil/trace.go:171","msg":"trace[97501625] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"160.400437ms","start":"2026-01-30T09:36:35.372397Z","end":"2026-01-30T09:36:35.532798Z","steps":["trace[97501625] 'process raft request' (duration: 159.905724ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:36:37.125036Z","caller":"traceutil/trace.go:171","msg":"trace[1872387268] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"340.965173ms","start":"2026-01-30T09:36:36.784047Z","end":"2026-01-30T09:36:37.125012Z","steps":["trace[1872387268] 'process raft request' (duration: 340.789108ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:37.125175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:36.784032Z","time spent":"341.072346ms","remote":"127.0.0.1:52030","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-01-30T09:36:37.208962Z","caller":"traceutil/trace.go:171","msg":"trace[1346096137] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"423.465296ms","start":"2026-01-30T09:36:36.78548Z","end":"2026-01-30T09:36:37.208945Z","steps":["trace[1346096137] 'process raft request' (duration: 423.380994ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:37.20908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:36.785473Z","time spent":"423.545659ms","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T09:36:37.209167Z","caller":"traceutil/trace.go:171","msg":"trace[1158075167] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"424.873494ms","start":"2026-01-30T09:36:36.784287Z","end":"2026-01-30T09:36:37.209161Z","steps":["trace[1158075167] 'process raft request' (duration: 423.035396ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:37.209214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:36.784265Z","time spent":"424.915245ms","remote":"127.0.0.1:52030","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-01-30T09:36:37.209323Z","caller":"traceutil/trace.go:171","msg":"trace[1437360885] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"423.805126ms","start":"2026-01-30T09:36:36.785512Z","end":"2026-01-30T09:36:37.209317Z","steps":["trace[1437360885] 'process raft request' (duration: 423.408656ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:37.209356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:36.785509Z","time spent":"423.829137ms","remote":"127.0.0.1:52030","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-01-30T09:36:37.20965Z","caller":"traceutil/trace.go:171","msg":"trace[2134864247] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"300.892298ms","start":"2026-01-30T09:36:36.908751Z","end":"2026-01-30T09:36:37.209643Z","steps":["trace[2134864247] 'process raft request' (duration: 300.535158ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:37.21386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:36.908717Z","time spent":"300.94993ms","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T09:36:46.139752Z","caller":"traceutil/trace.go:171","msg":"trace[64908402] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"561.026953ms","start":"2026-01-30T09:36:45.578717Z","end":"2026-01-30T09:36:46.139743Z","steps":["trace[64908402] 'process raft request' (duration: 560.669543ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:46.139886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:45.578705Z","time spent":"561.080615ms","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T09:36:46.139556Z","caller":"traceutil/trace.go:171","msg":"trace[211013462] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"492.061185ms","start":"2026-01-30T09:36:45.647478Z","end":"2026-01-30T09:36:46.139539Z","steps":["trace[211013462] 'read index received' (duration: 491.84495ms)","trace[211013462] 'applied index is now lower than readState.Index' (duration: 215.115µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:36:46.140201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.720633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2252"} {"level":"info","ts":"2026-01-30T09:36:46.140223Z","caller":"traceutil/trace.go:171","msg":"trace[1871408578] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:612; }","duration":"492.766604ms","start":"2026-01-30T09:36:45.647449Z","end":"2026-01-30T09:36:46.140215Z","steps":["trace[1871408578] 'agreement among raft nodes before linearized reading' (duration: 492.648531ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:46.140241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:45.647433Z","time spent":"492.803395ms","remote":"127.0.0.1:55710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":2275,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-01-30T09:36:46.554469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.650446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2252"} {"level":"info","ts":"2026-01-30T09:36:46.554527Z","caller":"traceutil/trace.go:171","msg":"trace[1489375543] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:612; }","duration":"403.730568ms","start":"2026-01-30T09:36:46.150781Z","end":"2026-01-30T09:36:46.554511Z","steps":["trace[1489375543] 'range keys from in-memory index tree' (duration: 403.580724ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:46.554549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:46.150769Z","time spent":"403.775589ms","remote":"127.0.0.1:55710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":2275,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"info","ts":"2026-01-30T09:36:47.082244Z","caller":"traceutil/trace.go:171","msg":"trace[1192672490] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"495.318622ms","start":"2026-01-30T09:36:46.58691Z","end":"2026-01-30T09:36:47.082228Z","steps":["trace[1192672490] 'read index received' (duration: 495.131817ms)","trace[1192672490] 'applied index is now lower than readState.Index' (duration: 186.375µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:36:47.082403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.477646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2252"} {"level":"info","ts":"2026-01-30T09:36:47.082421Z","caller":"traceutil/trace.go:171","msg":"trace[1806337742] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:613; }","duration":"495.529707ms","start":"2026-01-30T09:36:46.586886Z","end":"2026-01-30T09:36:47.082416Z","steps":["trace[1806337742] 'agreement among raft nodes before linearized reading' (duration: 495.400483ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:47.082441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:46.586872Z","time spent":"495.562998ms","remote":"127.0.0.1:55710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":2275,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"info","ts":"2026-01-30T09:36:47.082614Z","caller":"traceutil/trace.go:171","msg":"trace[1011788065] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"503.432125ms","start":"2026-01-30T09:36:46.579166Z","end":"2026-01-30T09:36:47.082598Z","steps":["trace[1011788065] 'process raft request' (duration: 502.934332ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:47.08267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:46.579151Z","time spent":"503.476216ms","remote":"127.0.0.1:51826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T09:36:53.766845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.212939ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-30T09:36:53.766931Z","caller":"traceutil/trace.go:171","msg":"trace[1081582309] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"500.526899ms","start":"2026-01-30T09:36:53.266387Z","end":"2026-01-30T09:36:53.766914Z","steps":["trace[1081582309] 'read index received' (duration: 67.462µs)","trace[1081582309] 'applied index is now lower than readState.Index' (duration: 500.458227ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:36:53.767488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.111414ms","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-01-30T09:36:53.767531Z","caller":"traceutil/trace.go:171","msg":"trace[753548129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"501.204516ms","start":"2026-01-30T09:36:53.266299Z","end":"2026-01-30T09:36:53.767503Z","steps":["trace[753548129] 'agreement among raft nodes before linearized reading' (duration: 501.093933ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:53.76756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:53.266274Z","time spent":"501.277428ms","remote":"127.0.0.1:52030","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-01-30T09:36:53.767721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.524585ms","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-01-30T09:36:53.767739Z","caller":"traceutil/trace.go:171","msg":"trace[265970922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"482.576885ms","start":"2026-01-30T09:36:53.285156Z","end":"2026-01-30T09:36:53.767733Z","steps":["trace[265970922] 'agreement among raft nodes before linearized reading' (duration: 482.524534ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:53.767757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:53.285131Z","time spent":"482.620847ms","remote":"127.0.0.1:52030","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-01-30T09:36:53.767877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.185468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:36:53.767896Z","caller":"traceutil/trace.go:171","msg":"trace[1059179351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"295.241489ms","start":"2026-01-30T09:36:53.472647Z","end":"2026-01-30T09:36:53.767888Z","steps":["trace[1059179351] 'agreement among raft nodes before linearized reading' (duration: 295.213429ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:53.768011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.96249ms","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-01-30T09:36:53.76803Z","caller":"traceutil/trace.go:171","msg":"trace[1741706930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"456.998382ms","start":"2026-01-30T09:36:53.311027Z","end":"2026-01-30T09:36:53.768025Z","steps":["trace[1741706930] 'agreement among raft nodes before linearized reading' (duration: 456.94409ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:53.768049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:53.31102Z","time spent":"457.024922ms","remote":"127.0.0.1:52030","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-01-30T09:36:53.768193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.13032ms","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-01-30T09:36:53.768218Z","caller":"traceutil/trace.go:171","msg":"trace[467977322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:680; }","duration":"464.212662ms","start":"2026-01-30T09:36:53.303998Z","end":"2026-01-30T09:36:53.76821Z","steps":["trace[467977322] 'agreement among raft nodes before linearized reading' (duration: 464.14375ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:36:53.768238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:53.303976Z","time spent":"464.258053ms","remote":"127.0.0.1:52030","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-01-30T09:36:53.95612Z","caller":"traceutil/trace.go:171","msg":"trace[1110020770] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"175.313719ms","start":"2026-01-30T09:36:53.780781Z","end":"2026-01-30T09:36:53.956095Z","steps":["trace[1110020770] 'process raft request' (duration: 175.270538ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:36:53.956535Z","caller":"traceutil/trace.go:171","msg":"trace[1051363051] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"179.299895ms","start":"2026-01-30T09:36:53.777221Z","end":"2026-01-30T09:36:53.956521Z","steps":["trace[1051363051] 'process raft request' (duration: 178.560295ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:36:53.956711Z","caller":"traceutil/trace.go:171","msg":"trace[1449807896] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"179.379726ms","start":"2026-01-30T09:36:53.77732Z","end":"2026-01-30T09:36:53.9567Z","steps":["trace[1449807896] 'process raft request' (duration: 178.684778ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:36:53.957178Z","caller":"traceutil/trace.go:171","msg":"trace[896663808] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"112.241978ms","start":"2026-01-30T09:36:53.844924Z","end":"2026-01-30T09:36:53.957166Z","steps":["trace[896663808] 'process raft request' (duration: 112.172716ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:36:53.957572Z","caller":"traceutil/trace.go:171","msg":"trace[1003469171] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:708; }","duration":"114.082766ms","start":"2026-01-30T09:36:53.843477Z","end":"2026-01-30T09:36:53.95756Z","steps":["trace[1003469171] 'read index received' (duration: 114.077746ms)","trace[1003469171] 'applied index is now lower than readState.Index' (duration: 4.17µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:36:53.961425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.916547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-p8dl6\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-01-30T09:36:53.961483Z","caller":"traceutil/trace.go:171","msg":"trace[1971649336] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-p8dl6; range_end:; response_count:1; response_revision:685; }","duration":"117.997519ms","start":"2026-01-30T09:36:53.84347Z","end":"2026-01-30T09:36:53.961468Z","steps":["trace[1971649336] 'agreement among raft nodes before linearized reading' (duration: 114.139007ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:00.496366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388031575341050,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T09:37:01.005817Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388031575341050,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T09:37:01.106465Z","caller":"traceutil/trace.go:171","msg":"trace[1471854352] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"1.249188227s","start":"2026-01-30T09:36:59.857266Z","end":"2026-01-30T09:37:01.106454Z","steps":["trace[1471854352] 'process raft request' (duration: 1.248860018s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:01.106566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:59.857254Z","time spent":"1.249250899s","remote":"127.0.0.1:52030","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-01-30T09:37:01.106238Z","caller":"traceutil/trace.go:171","msg":"trace[1939325520] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"1.110457004s","start":"2026-01-30T09:36:59.995761Z","end":"2026-01-30T09:37:01.106218Z","steps":["trace[1939325520] 'read index received' (duration: 1.110317581s)","trace[1939325520] 'applied index is now lower than readState.Index' (duration: 138.723µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:37:01.106909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111152433s","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-01-30T09:37:01.106931Z","caller":"traceutil/trace.go:171","msg":"trace[1643747053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:705; }","duration":"1.111207424s","start":"2026-01-30T09:36:59.995716Z","end":"2026-01-30T09:37:01.106924Z","steps":["trace[1643747053] 'agreement among raft nodes before linearized reading' (duration: 1.111112492s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:01.106951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:59.995698Z","time spent":"1.111248316s","remote":"127.0.0.1:52030","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-01-30T09:37:01.107068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107557467s","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-01-30T09:37:01.107086Z","caller":"traceutil/trace.go:171","msg":"trace[31786687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:705; }","duration":"1.107593578s","start":"2026-01-30T09:36:59.999486Z","end":"2026-01-30T09:37:01.10708Z","steps":["trace[31786687] 'agreement among raft nodes before linearized reading' (duration: 1.107543267s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:01.107102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:59.999476Z","time spent":"1.107622439s","remote":"127.0.0.1:52030","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-01-30T09:37:01.107221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.043479ms","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-01-30T09:37:01.10724Z","caller":"traceutil/trace.go:171","msg":"trace[222776284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:705; }","duration":"870.095469ms","start":"2026-01-30T09:37:00.23714Z","end":"2026-01-30T09:37:01.107235Z","steps":["trace[222776284] 'agreement among raft nodes before linearized reading' (duration: 870.046968ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:01.107266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:00.237123Z","time spent":"870.130301ms","remote":"127.0.0.1:52030","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-01-30T09:37:01.107369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107374112s","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-01-30T09:37:01.107385Z","caller":"traceutil/trace.go:171","msg":"trace[2064473373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:705; }","duration":"1.107406083s","start":"2026-01-30T09:36:59.999974Z","end":"2026-01-30T09:37:01.10738Z","steps":["trace[2064473373] 'agreement among raft nodes before linearized reading' (duration: 1.107357462s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:01.107402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:36:59.999967Z","time spent":"1.107431014s","remote":"127.0.0.1:52030","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-01-30T09:37:13.262409Z","caller":"traceutil/trace.go:171","msg":"trace[1457883338] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"112.562934ms","start":"2026-01-30T09:37:13.149831Z","end":"2026-01-30T09:37:13.262394Z","steps":["trace[1457883338] 'read index received' (duration: 112.429281ms)","trace[1457883338] 'applied index is now lower than readState.Index' (duration: 132.713µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:37:13.262576Z","caller":"traceutil/trace.go:171","msg":"trace[858835279] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"119.454618ms","start":"2026-01-30T09:37:13.143114Z","end":"2026-01-30T09:37:13.262569Z","steps":["trace[858835279] 'process raft request' (duration: 119.191521ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:13.262749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.904093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-30T09:37:13.262779Z","caller":"traceutil/trace.go:171","msg":"trace[1207714897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:745; }","duration":"112.951305ms","start":"2026-01-30T09:37:13.149819Z","end":"2026-01-30T09:37:13.26277Z","steps":["trace[1207714897] 'agreement among raft nodes before linearized reading' (duration: 112.864553ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:15.396262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.157757ms","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-01-30T09:37:15.396323Z","caller":"traceutil/trace.go:171","msg":"trace[1646068886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:750; }","duration":"123.224148ms","start":"2026-01-30T09:37:15.273084Z","end":"2026-01-30T09:37:15.396308Z","steps":["trace[1646068886] 'agreement among raft nodes before linearized reading' (duration: 91.015429ms)","trace[1646068886] 'range keys from in-memory index tree' (duration: 32.079276ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:37:15.396526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.290383ms","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-01-30T09:37:15.396551Z","caller":"traceutil/trace.go:171","msg":"trace[2028203735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"122.313924ms","start":"2026-01-30T09:37:15.274229Z","end":"2026-01-30T09:37:15.396543Z","steps":["trace[2028203735] 'agreement among raft nodes before linearized reading' (duration: 122.231821ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:15.396808Z","caller":"traceutil/trace.go:171","msg":"trace[1165808861] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"122.847288ms","start":"2026-01-30T09:37:15.27395Z","end":"2026-01-30T09:37:15.396798Z","steps":["trace[1165808861] 'process raft request' (duration: 107.725805ms)","trace[1165808861] 'compare' (duration: 14.465956ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:37:15.397238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.268606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-30T09:37:15.397299Z","caller":"traceutil/trace.go:171","msg":"trace[956026510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"121.343159ms","start":"2026-01-30T09:37:15.275949Z","end":"2026-01-30T09:37:15.397292Z","steps":["trace[956026510] 'agreement among raft nodes before linearized reading' (duration: 121.240886ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:17.575124Z","caller":"traceutil/trace.go:171","msg":"trace[1393164661] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"169.234657ms","start":"2026-01-30T09:37:17.405863Z","end":"2026-01-30T09:37:17.575098Z","steps":["trace[1393164661] 'process raft request' (duration: 152.433458ms)","trace[1393164661] 'compare' (duration: 11.9657ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:37:17.576926Z","caller":"traceutil/trace.go:171","msg":"trace[833038391] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"150.826995ms","start":"2026-01-30T09:37:17.426082Z","end":"2026-01-30T09:37:17.576909Z","steps":["trace[833038391] 'read index received' (duration: 132.166017ms)","trace[833038391] 'applied index is now lower than readState.Index' (duration: 18.658678ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:37:17.577866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.803777ms","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-01-30T09:37:17.578045Z","caller":"traceutil/trace.go:171","msg":"trace[1401241309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"143.973902ms","start":"2026-01-30T09:37:17.43403Z","end":"2026-01-30T09:37:17.578004Z","steps":["trace[1401241309] 'agreement among raft nodes before linearized reading' (duration: 143.747016ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:17.578195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.441158ms","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-01-30T09:37:17.578278Z","caller":"traceutil/trace.go:171","msg":"trace[820122094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:756; }","duration":"143.5371ms","start":"2026-01-30T09:37:17.434735Z","end":"2026-01-30T09:37:17.578272Z","steps":["trace[820122094] 'agreement among raft nodes before linearized reading' (duration: 143.428257ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:17.577666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.562194ms","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-01-30T09:37:17.578748Z","caller":"traceutil/trace.go:171","msg":"trace[1069669782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:756; }","duration":"152.637104ms","start":"2026-01-30T09:37:17.426062Z","end":"2026-01-30T09:37:17.578699Z","steps":["trace[1069669782] 'agreement among raft nodes before linearized reading' (duration: 151.328859ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:17.68895Z","caller":"traceutil/trace.go:171","msg":"trace[1248699064] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"105.947808ms","start":"2026-01-30T09:37:17.582983Z","end":"2026-01-30T09:37:17.68893Z","steps":["trace[1248699064] 'process raft request' (duration: 102.339461ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:17.689319Z","caller":"traceutil/trace.go:171","msg":"trace[1167680022] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"105.297651ms","start":"2026-01-30T09:37:17.584007Z","end":"2026-01-30T09:37:17.689304Z","steps":["trace[1167680022] 'process raft request' (duration: 104.864569ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:28.859554Z","caller":"traceutil/trace.go:171","msg":"trace[1907043503] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"315.349105ms","start":"2026-01-30T09:37:28.544182Z","end":"2026-01-30T09:37:28.859532Z","steps":["trace[1907043503] 'process raft request' (duration: 315.199731ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:28.859757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:28.544145Z","time spent":"315.480718ms","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T09:37:33.612174Z","caller":"traceutil/trace.go:171","msg":"trace[1643255309] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"113.445108ms","start":"2026-01-30T09:37:33.498714Z","end":"2026-01-30T09:37:33.612159Z","steps":["trace[1643255309] 'process raft request' (duration: 113.211362ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:33.87187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.87093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T09:37:33.871934Z","caller":"traceutil/trace.go:171","msg":"trace[678385632] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:886; }","duration":"158.801168ms","start":"2026-01-30T09:37:33.713122Z","end":"2026-01-30T09:37:33.871923Z","steps":["trace[678385632] 'read index received' (duration: 14.261µs)","trace[678385632] 'applied index is now lower than readState.Index' (duration: 158.785787ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:37:33.872056Z","caller":"traceutil/trace.go:171","msg":"trace[985713701] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"204.651371ms","start":"2026-01-30T09:37:33.667396Z","end":"2026-01-30T09:37:33.872048Z","steps":["trace[985713701] 'process raft request' (duration: 45.547196ms)","trace[985713701] 'compare' (duration: 158.681504ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:37:33.872212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.089606ms","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-01-30T09:37:33.872232Z","caller":"traceutil/trace.go:171","msg":"trace[1751191796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:853; }","duration":"159.147517ms","start":"2026-01-30T09:37:33.713077Z","end":"2026-01-30T09:37:33.872224Z","steps":["trace[1751191796] 'agreement among raft nodes before linearized reading' (duration: 159.074015ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:33.872323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.185237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T09:37:33.872341Z","caller":"traceutil/trace.go:171","msg":"trace[1373204822] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:853; }","duration":"129.224878ms","start":"2026-01-30T09:37:33.743109Z","end":"2026-01-30T09:37:33.872334Z","steps":["trace[1373204822] 'agreement among raft nodes before linearized reading' (duration: 129.195557ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:34.42048Z","caller":"traceutil/trace.go:171","msg":"trace[431904430] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"540.023041ms","start":"2026-01-30T09:37:33.88037Z","end":"2026-01-30T09:37:34.420393Z","steps":["trace[431904430] 'process raft request' (duration: 448.648833ms)","trace[431904430] 'compare' (duration: 91.096531ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:37:34.421213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:33.880353Z","time spent":"540.208896ms","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T09:37:34.421673Z","caller":"traceutil/trace.go:171","msg":"trace[1504210344] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"530.979909ms","start":"2026-01-30T09:37:33.89068Z","end":"2026-01-30T09:37:34.42166Z","steps":["trace[1504210344] 'process raft request' (duration: 530.947618ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:34.421741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:33.890672Z","time spent":"531.033301ms","remote":"127.0.0.1:52030","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-01-30T09:37:34.422526Z","caller":"traceutil/trace.go:171","msg":"trace[1356352096] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"540.851373ms","start":"2026-01-30T09:37:33.88166Z","end":"2026-01-30T09:37:34.422511Z","steps":["trace[1356352096] 'process raft request' (duration: 539.905087ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:34.42267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:33.88165Z","time spent":"540.924484ms","remote":"127.0.0.1:52030","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-01-30T09:37:35.600159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.390845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T09:37:35.600223Z","caller":"traceutil/trace.go:171","msg":"trace[1959230559] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:856; }","duration":"276.486458ms","start":"2026-01-30T09:37:35.323717Z","end":"2026-01-30T09:37:35.600204Z","steps":["trace[1959230559] 'count revisions from in-memory index tree' (duration: 276.344005ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:35.60038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.628254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T09:37:35.600464Z","caller":"traceutil/trace.go:171","msg":"trace[1451214817] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:856; }","duration":"876.727307ms","start":"2026-01-30T09:37:34.723713Z","end":"2026-01-30T09:37:35.60044Z","steps":["trace[1451214817] 'count revisions from in-memory index tree' (duration: 876.563903ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:35.600511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:34.723665Z","time spent":"876.8345ms","remote":"127.0.0.1:51964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-01-30T09:37:35.600417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.056674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:37:35.600735Z","caller":"traceutil/trace.go:171","msg":"trace[1252145914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:856; }","duration":"126.402883ms","start":"2026-01-30T09:37:35.47432Z","end":"2026-01-30T09:37:35.600723Z","steps":["trace[1252145914] 'range keys from in-memory index tree' (duration: 125.996303ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:35.773417Z","caller":"traceutil/trace.go:171","msg":"trace[878982052] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"153.234759ms","start":"2026-01-30T09:37:35.620158Z","end":"2026-01-30T09:37:35.773393Z","steps":["trace[878982052] 'process raft request' (duration: 151.339039ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:40.242198Z","caller":"traceutil/trace.go:171","msg":"trace[1888427519] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"456.424171ms","start":"2026-01-30T09:37:39.785741Z","end":"2026-01-30T09:37:40.242165Z","steps":["trace[1888427519] 'process raft request' (duration: 456.254676ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:40.242383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:39.785731Z","time spent":"456.559154ms","remote":"127.0.0.1:52030","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-01-30T09:37:40.242653Z","caller":"traceutil/trace.go:171","msg":"trace[2011686453] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:904; }","duration":"351.061309ms","start":"2026-01-30T09:37:39.891585Z","end":"2026-01-30T09:37:40.242646Z","steps":["trace[2011686453] 'read index received' (duration: 351.057718ms)","trace[2011686453] 'applied index is now lower than readState.Index' (duration: 3.031µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:37:40.242756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.169831ms","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-01-30T09:37:40.24278Z","caller":"traceutil/trace.go:171","msg":"trace[1590277833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:869; }","duration":"351.234343ms","start":"2026-01-30T09:37:39.891539Z","end":"2026-01-30T09:37:40.242773Z","steps":["trace[1590277833] 'agreement among raft nodes before linearized reading' (duration: 351.13329ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:40.2428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:39.89152Z","time spent":"351.274585ms","remote":"127.0.0.1:52030","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-01-30T09:37:40.690732Z","caller":"traceutil/trace.go:171","msg":"trace[1480120410] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"130.7651ms","start":"2026-01-30T09:37:40.559952Z","end":"2026-01-30T09:37:40.690717Z","steps":["trace[1480120410] 'process raft request' (duration: 130.673408ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:40.719616Z","caller":"traceutil/trace.go:171","msg":"trace[1054729708] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:906; }","duration":"115.303417ms","start":"2026-01-30T09:37:40.604295Z","end":"2026-01-30T09:37:40.719598Z","steps":["trace[1054729708] 'read index received' (duration: 86.799436ms)","trace[1054729708] 'applied index is now lower than readState.Index' (duration: 28.503151ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:37:40.719759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.43991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T09:37:40.71979Z","caller":"traceutil/trace.go:171","msg":"trace[260854740] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:873; }","duration":"115.490112ms","start":"2026-01-30T09:37:40.604291Z","end":"2026-01-30T09:37:40.719781Z","steps":["trace[260854740] 'agreement among raft nodes before linearized reading' (duration: 115.393229ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:40.72006Z","caller":"traceutil/trace.go:171","msg":"trace[1612550497] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"159.387014ms","start":"2026-01-30T09:37:40.560661Z","end":"2026-01-30T09:37:40.720048Z","steps":["trace[1612550497] 'process raft request' (duration: 158.817619ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:40.720173Z","caller":"traceutil/trace.go:171","msg":"trace[220438035] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"155.574302ms","start":"2026-01-30T09:37:40.564591Z","end":"2026-01-30T09:37:40.720165Z","steps":["trace[220438035] 'process raft request' (duration: 154.975066ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:42.427309Z","caller":"traceutil/trace.go:171","msg":"trace[1145197160] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"106.723379ms","start":"2026-01-30T09:37:42.320567Z","end":"2026-01-30T09:37:42.42729Z","steps":["trace[1145197160] 'read index received' (duration: 106.549694ms)","trace[1145197160] 'applied index is now lower than readState.Index' (duration: 173.074µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:37:42.427475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.889173ms","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-01-30T09:37:42.4275Z","caller":"traceutil/trace.go:171","msg":"trace[766022386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:875; }","duration":"106.950785ms","start":"2026-01-30T09:37:42.320539Z","end":"2026-01-30T09:37:42.42749Z","steps":["trace[766022386] 'agreement among raft nodes before linearized reading' (duration: 106.824441ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:42.427755Z","caller":"traceutil/trace.go:171","msg":"trace[345049925] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"179.427109ms","start":"2026-01-30T09:37:42.24832Z","end":"2026-01-30T09:37:42.427747Z","steps":["trace[345049925] 'process raft request' (duration: 178.867264ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:42.994013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388031575341473,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T09:37:43.078234Z","caller":"traceutil/trace.go:171","msg":"trace[1605412470] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"645.044183ms","start":"2026-01-30T09:37:42.433167Z","end":"2026-01-30T09:37:43.078212Z","steps":["trace[1605412470] 'process raft request' (duration: 644.94869ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:43.078353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:42.433153Z","time spent":"645.138515ms","remote":"127.0.0.1:52030","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-01-30T09:37:43.640879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.515246ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-30T09:37:43.640977Z","caller":"traceutil/trace.go:171","msg":"trace[1360950858] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"1.167894647s","start":"2026-01-30T09:37:42.473068Z","end":"2026-01-30T09:37:43.640962Z","steps":["trace[1360950858] 'read index received' (duration: 605.532759ms)","trace[1360950858] 'applied index is now lower than readState.Index' (duration: 562.360478ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:37:43.642572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.749465ms","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-01-30T09:37:43.642611Z","caller":"traceutil/trace.go:171","msg":"trace[1695189878] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:876; }","duration":"947.264359ms","start":"2026-01-30T09:37:42.695335Z","end":"2026-01-30T09:37:43.6426Z","steps":["trace[1695189878] 'agreement among raft nodes before linearized reading' (duration: 946.639802ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:43.642637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:42.695299Z","time spent":"947.33181ms","remote":"127.0.0.1:52030","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-01-30T09:37:43.642789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169721885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:37:43.642804Z","caller":"traceutil/trace.go:171","msg":"trace[1268520925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"1.169757086s","start":"2026-01-30T09:37:42.473041Z","end":"2026-01-30T09:37:43.642798Z","steps":["trace[1268520925] 'agreement among raft nodes before linearized reading' (duration: 1.169731745s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:43.642815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:42.473028Z","time spent":"1.169784627s","remote":"127.0.0.1:51738","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-01-30T09:37:43.64317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.782472ms","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-01-30T09:37:43.643193Z","caller":"traceutil/trace.go:171","msg":"trace[1722721842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:876; }","duration":"917.864264ms","start":"2026-01-30T09:37:42.725322Z","end":"2026-01-30T09:37:43.643186Z","steps":["trace[1722721842] 'agreement among raft nodes before linearized reading' (duration: 917.72491ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:43.643207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:42.725309Z","time spent":"917.894115ms","remote":"127.0.0.1:52030","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-01-30T09:37:43.643433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.035959ms","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-01-30T09:37:43.643519Z","caller":"traceutil/trace.go:171","msg":"trace[447861373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:876; }","duration":"918.121781ms","start":"2026-01-30T09:37:42.725391Z","end":"2026-01-30T09:37:43.643513Z","steps":["trace[447861373] 'agreement among raft nodes before linearized reading' (duration: 917.884315ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:43.643534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:42.72538Z","time spent":"918.149741ms","remote":"127.0.0.1:52030","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":"info","ts":"2026-01-30T09:37:45.316082Z","caller":"traceutil/trace.go:171","msg":"trace[232460160] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"223.477233ms","start":"2026-01-30T09:37:45.092584Z","end":"2026-01-30T09:37:45.316061Z","steps":["trace[232460160] 'process raft request' (duration: 223.34027ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:45.845237Z","caller":"traceutil/trace.go:171","msg":"trace[1036026162] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"148.01569ms","start":"2026-01-30T09:37:45.697185Z","end":"2026-01-30T09:37:45.845201Z","steps":["trace[1036026162] 'process raft request' (duration: 105.636029ms)","trace[1036026162] 'compare' (duration: 42.222946ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:37:45.853683Z","caller":"traceutil/trace.go:171","msg":"trace[1084182300] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"155.634114ms","start":"2026-01-30T09:37:45.698034Z","end":"2026-01-30T09:37:45.853668Z","steps":["trace[1084182300] 'process raft request' (duration: 155.564122ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:46.689177Z","caller":"traceutil/trace.go:171","msg":"trace[563127684] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"201.191439ms","start":"2026-01-30T09:37:46.487958Z","end":"2026-01-30T09:37:46.68915Z","steps":["trace[563127684] 'read index received' (duration: 200.917371ms)","trace[563127684] 'applied index is now lower than readState.Index' (duration: 272.938µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:37:46.68933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.352843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:37:46.689362Z","caller":"traceutil/trace.go:171","msg":"trace[12463818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"201.449865ms","start":"2026-01-30T09:37:46.487903Z","end":"2026-01-30T09:37:46.689353Z","steps":["trace[12463818] 'agreement among raft nodes before linearized reading' (duration: 201.344302ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:46.689773Z","caller":"traceutil/trace.go:171","msg":"trace[1372058246] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"235.454253ms","start":"2026-01-30T09:37:46.454307Z","end":"2026-01-30T09:37:46.689761Z","steps":["trace[1372058246] 'process raft request' (duration: 234.656222ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:47.591339Z","caller":"traceutil/trace.go:171","msg":"trace[1276348842] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"233.507113ms","start":"2026-01-30T09:37:47.357809Z","end":"2026-01-30T09:37:47.591316Z","steps":["trace[1276348842] 'process raft request' (duration: 233.359689ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:51.735228Z","caller":"traceutil/trace.go:171","msg":"trace[573737647] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"124.899373ms","start":"2026-01-30T09:37:51.610308Z","end":"2026-01-30T09:37:51.735207Z","steps":["trace[573737647] 'process raft request' (duration: 124.537153ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:52.47313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.865372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T09:37:52.473353Z","caller":"traceutil/trace.go:171","msg":"trace[1180408517] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"316.511757ms","start":"2026-01-30T09:37:52.156823Z","end":"2026-01-30T09:37:52.473335Z","steps":["trace[1180408517] 'process raft request' (duration: 316.431855ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:37:52.473381Z","caller":"traceutil/trace.go:171","msg":"trace[168211664] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"578.752734ms","start":"2026-01-30T09:37:51.894601Z","end":"2026-01-30T09:37:52.473354Z","steps":["trace[168211664] 'process raft request' (duration: 58.566243ms)","trace[168211664] 'compare' (duration: 519.649007ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:37:52.473433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:52.156795Z","time spent":"316.601579ms","remote":"127.0.0.1:52030","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-01-30T09:37:52.473528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:51.894595Z","time spent":"578.847417ms","remote":"127.0.0.1:52030","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-01-30T09:37:53.105883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.107198ms","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-01-30T09:37:53.105954Z","caller":"traceutil/trace.go:171","msg":"trace[725776906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"391.264822ms","start":"2026-01-30T09:37:52.714674Z","end":"2026-01-30T09:37:53.105939Z","steps":["trace[725776906] 'range keys from in-memory index tree' (duration: 390.932853ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:53.105983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:37:52.714653Z","time spent":"391.324763ms","remote":"127.0.0.1:52030","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-01-30T09:37:53.285996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.187647ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-30T09:37:53.286387Z","caller":"traceutil/trace.go:171","msg":"trace[149255727] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"176.141901ms","start":"2026-01-30T09:37:53.110216Z","end":"2026-01-30T09:37:53.286358Z","steps":["trace[149255727] 'process raft request' (duration: 175.896894ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:57.415478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.676928ms","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-01-30T09:37:57.415577Z","caller":"traceutil/trace.go:171","msg":"trace[927660267] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:932; }","duration":"151.877683ms","start":"2026-01-30T09:37:57.263674Z","end":"2026-01-30T09:37:57.415552Z","steps":["trace[927660267] 'count revisions from in-memory index tree' (duration: 151.500414ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:37:57.417269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.346408ms","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-01-30T09:37:57.417314Z","caller":"traceutil/trace.go:171","msg":"trace[468140388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:932; }","duration":"118.43505ms","start":"2026-01-30T09:37:57.298864Z","end":"2026-01-30T09:37:57.417299Z","steps":["trace[468140388] 'range keys from in-memory index tree' (duration: 118.124173ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:38:00.850553Z","caller":"traceutil/trace.go:171","msg":"trace[619652715] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"321.794396ms","start":"2026-01-30T09:38:00.528734Z","end":"2026-01-30T09:38:00.850528Z","steps":["trace[619652715] 'process raft request' (duration: 321.663563ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:38:00.850696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:38:00.528722Z","time spent":"321.888679ms","remote":"127.0.0.1:52030","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-01-30T09:38:03.021943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.280721ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-30T09:38:03.022071Z","caller":"traceutil/trace.go:171","msg":"trace[1371522557] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"165.621931ms","start":"2026-01-30T09:38:02.856425Z","end":"2026-01-30T09:38:03.022047Z","steps":["trace[1371522557] 'read index received' (duration: 62.120175ms)","trace[1371522557] 'applied index is now lower than readState.Index' (duration: 103.496676ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:38:03.022286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.850967ms","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-01-30T09:38:03.022316Z","caller":"traceutil/trace.go:171","msg":"trace[1727301221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:964; }","duration":"165.929709ms","start":"2026-01-30T09:38:02.856376Z","end":"2026-01-30T09:38:03.022306Z","steps":["trace[1727301221] 'agreement among raft nodes before linearized reading' (duration: 165.730034ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:38:03.585358Z","caller":"traceutil/trace.go:171","msg":"trace[416437988] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"106.884107ms","start":"2026-01-30T09:38:03.47844Z","end":"2026-01-30T09:38:03.585324Z","steps":["trace[416437988] 'process raft request' (duration: 15.058891ms)","trace[416437988] 'compare' (duration: 90.935832ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:38:07.309715Z","caller":"traceutil/trace.go:171","msg":"trace[1896105119] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"159.831168ms","start":"2026-01-30T09:38:07.149851Z","end":"2026-01-30T09:38:07.309682Z","steps":["trace[1896105119] 'process raft request' (duration: 69.694866ms)","trace[1896105119] 'compare' (duration: 89.251138ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:38:07.905352Z","caller":"traceutil/trace.go:171","msg":"trace[684223440] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"224.557142ms","start":"2026-01-30T09:38:07.680762Z","end":"2026-01-30T09:38:07.905319Z","steps":["trace[684223440] 'read index received' (duration: 224.292175ms)","trace[684223440] 'applied index is now lower than readState.Index' (duration: 263.747µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:38:07.905678Z","caller":"traceutil/trace.go:171","msg":"trace[1991164803] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"302.623221ms","start":"2026-01-30T09:38:07.603043Z","end":"2026-01-30T09:38:07.905666Z","steps":["trace[1991164803] 'process raft request' (duration: 302.105208ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:38:07.905866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:38:07.603028Z","time spent":"302.682954ms","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T09:38:07.906171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.413695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-30T09:38:07.906266Z","caller":"traceutil/trace.go:171","msg":"trace[947231809] range","detail":"{range_begin:/registry/masterleases/162.253.55.140; range_end:; response_count:1; response_revision:979; }","duration":"225.493227ms","start":"2026-01-30T09:38:07.680707Z","end":"2026-01-30T09:38:07.9062Z","steps":["trace[947231809] 'agreement among raft nodes before linearized reading' (duration: 225.346383ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:38:07.906458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.565259ms","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-01-30T09:38:07.906487Z","caller":"traceutil/trace.go:171","msg":"trace[1384416393] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:979; }","duration":"222.60761ms","start":"2026-01-30T09:38:07.683871Z","end":"2026-01-30T09:38:07.906478Z","steps":["trace[1384416393] 'agreement among raft nodes before linearized reading' (duration: 222.548119ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:38:08.061954Z","caller":"traceutil/trace.go:171","msg":"trace[395612583] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"123.50662ms","start":"2026-01-30T09:38:07.938412Z","end":"2026-01-30T09:38:08.061919Z","steps":["trace[395612583] 'process raft request' (duration: 73.392775ms)","trace[395612583] 'compare' (duration: 49.626562ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:38:08.064341Z","caller":"traceutil/trace.go:171","msg":"trace[501938] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"123.614713ms","start":"2026-01-30T09:38:07.940702Z","end":"2026-01-30T09:38:08.064316Z","steps":["trace[501938] 'process raft request' (duration: 123.548661ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:38:08.450818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.389982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T09:38:08.451173Z","caller":"traceutil/trace.go:171","msg":"trace[595161359] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"255.959368ms","start":"2026-01-30T09:38:08.195164Z","end":"2026-01-30T09:38:08.451124Z","steps":["trace[595161359] 'read index received' (duration: 46.652µs)","trace[595161359] 'applied index is now lower than readState.Index' (duration: 255.910606ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:38:08.451809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.617156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:38:08.451907Z","caller":"traceutil/trace.go:171","msg":"trace[998157509] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:983; }","duration":"256.78ms","start":"2026-01-30T09:38:08.195112Z","end":"2026-01-30T09:38:08.451892Z","steps":["trace[998157509] 'agreement among raft nodes before linearized reading' (duration: 256.638076ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:38:08.452092Z","caller":"traceutil/trace.go:171","msg":"trace[341165984] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"316.505971ms","start":"2026-01-30T09:38:08.135569Z","end":"2026-01-30T09:38:08.452074Z","steps":["trace[341165984] 'process raft request' (duration: 44.747812ms)","trace[341165984] 'compare' (duration: 270.226308ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:38:08.452207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:38:08.13554Z","time spent":"316.627544ms","remote":"127.0.0.1:52030","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-01-30T09:38:08.451846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.838466ms","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-01-30T09:38:08.452391Z","caller":"traceutil/trace.go:171","msg":"trace[1888858336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:983; }","duration":"223.418061ms","start":"2026-01-30T09:38:08.228959Z","end":"2026-01-30T09:38:08.452377Z","steps":["trace[1888858336] 'agreement among raft nodes before linearized reading' (duration: 222.761523ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:38:08.979519Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388031575341729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T09:38:09.583417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.818868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T09:38:09.583589Z","caller":"traceutil/trace.go:171","msg":"trace[1539026825] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"1.125281304s","start":"2026-01-30T09:38:08.458285Z","end":"2026-01-30T09:38:09.583567Z","steps":["trace[1539026825] 'process raft request' (duration: 956.223791ms)","trace[1539026825] 'compare' (duration: 168.704514ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:38:09.58365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:38:08.458257Z","time spent":"1.125365087s","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T09:38:09.583664Z","caller":"traceutil/trace.go:171","msg":"trace[1034302418] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1025; }","duration":"1.104794269s","start":"2026-01-30T09:38:08.478857Z","end":"2026-01-30T09:38:09.583652Z","steps":["trace[1034302418] 'read index received' (duration: 935.737485ms)","trace[1034302418] 'applied index is now lower than readState.Index' (duration: 169.055533ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:38:09.583777Z","caller":"traceutil/trace.go:171","msg":"trace[1852443980] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"1.124476903s","start":"2026-01-30T09:38:08.459289Z","end":"2026-01-30T09:38:09.583766Z","steps":["trace[1852443980] 'process raft request' (duration: 1.124244926s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:38:09.583886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:38:08.459281Z","time spent":"1.124522514s","remote":"127.0.0.1:52030","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-01-30T09:38:09.58391Z","caller":"traceutil/trace.go:171","msg":"trace[446828403] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"1.105866126s","start":"2026-01-30T09:38:08.478036Z","end":"2026-01-30T09:38:09.583903Z","steps":["trace[446828403] 'process raft request' (duration: 1.105573878s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:38:09.583947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:38:08.478011Z","time spent":"1.105917288s","remote":"127.0.0.1:51826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-30T09:38:09.584041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105181979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:38:09.584058Z","caller":"traceutil/trace.go:171","msg":"trace[2071519926] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.123026064s","start":"2026-01-30T09:38:08.461025Z","end":"2026-01-30T09:38:09.584051Z","steps":["trace[2071519926] 'process raft request' (duration: 1.122555452s)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:38:09.584069Z","caller":"traceutil/trace.go:171","msg":"trace[1521036748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"1.105229879s","start":"2026-01-30T09:38:08.478829Z","end":"2026-01-30T09:38:09.584059Z","steps":["trace[1521036748] 'agreement among raft nodes before linearized reading' (duration: 1.105184628s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:38:09.584092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:38:08.461017Z","time spent":"1.123059925s","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-30T09:38:09.5841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:38:08.478819Z","time spent":"1.105272231s","remote":"127.0.0.1:51738","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-01-30T09:38:09.595356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.646122ms","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-01-30T09:38:09.595433Z","caller":"traceutil/trace.go:171","msg":"trace[1866136858] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:988; }","duration":"507.795926ms","start":"2026-01-30T09:38:09.08762Z","end":"2026-01-30T09:38:09.595416Z","steps":["trace[1866136858] 'agreement among raft nodes before linearized reading' (duration: 507.624861ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:38:09.595477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:38:09.087591Z","time spent":"507.875758ms","remote":"127.0.0.1:52030","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-01-30T09:38:09.596961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.632695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:38:09.597047Z","caller":"traceutil/trace.go:171","msg":"trace[637264659] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:988; }","duration":"951.391702ms","start":"2026-01-30T09:38:08.645642Z","end":"2026-01-30T09:38:09.597034Z","steps":["trace[637264659] 'agreement among raft nodes before linearized reading' (duration: 949.686647ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:38:09.597137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:38:08.645616Z","time spent":"951.510946ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-01-30T09:38:09.840574Z","caller":"traceutil/trace.go:171","msg":"trace[1978046863] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"233.057798ms","start":"2026-01-30T09:38:09.607487Z","end":"2026-01-30T09:38:09.840545Z","steps":["trace[1978046863] 'process raft request' (duration: 233.018797ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:38:09.841006Z","caller":"traceutil/trace.go:171","msg":"trace[555062611] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"234.284541ms","start":"2026-01-30T09:38:09.606705Z","end":"2026-01-30T09:38:09.84099Z","steps":["trace[555062611] 'process raft request' (duration: 233.685615ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:38:09.841013Z","caller":"traceutil/trace.go:171","msg":"trace[1606796155] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"241.092162ms","start":"2026-01-30T09:38:09.59987Z","end":"2026-01-30T09:38:09.840962Z","steps":["trace[1606796155] 'process raft request' (duration: 193.693599ms)","trace[1606796155] 'compare' (duration: 46.54876ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:38:09.84116Z","caller":"traceutil/trace.go:171","msg":"trace[868963621] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"236.681075ms","start":"2026-01-30T09:38:09.604469Z","end":"2026-01-30T09:38:09.84115Z","steps":["trace[868963621] 'read index received' (duration: 189.003915ms)","trace[868963621] 'applied index is now lower than readState.Index' (duration: 47.67535ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:38:09.841656Z","caller":"traceutil/trace.go:171","msg":"trace[1566228631] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"234.088486ms","start":"2026-01-30T09:38:09.607364Z","end":"2026-01-30T09:38:09.841453Z","steps":["trace[1566228631] 'process raft request' (duration: 233.10895ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:38:09.84174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.808958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:38:09.841818Z","caller":"traceutil/trace.go:171","msg":"trace[807112753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"237.356573ms","start":"2026-01-30T09:38:09.604417Z","end":"2026-01-30T09:38:09.841774Z","steps":["trace[807112753] 'agreement among raft nodes before linearized reading' (duration: 236.769527ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:38:10.239926Z","caller":"traceutil/trace.go:171","msg":"trace[61572801] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"171.640612ms","start":"2026-01-30T09:38:10.068249Z","end":"2026-01-30T09:38:10.239889Z","steps":["trace[61572801] 'read index received' (duration: 150.776557ms)","trace[61572801] 'applied index is now lower than readState.Index' (duration: 20.862685ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:38:10.240311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.052893ms","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-01-30T09:38:10.240336Z","caller":"traceutil/trace.go:171","msg":"trace[756510116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"172.143116ms","start":"2026-01-30T09:38:10.068185Z","end":"2026-01-30T09:38:10.240328Z","steps":["trace[756510116] 'agreement among raft nodes before linearized reading' (duration: 171.996392ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:38:10.240339Z","caller":"traceutil/trace.go:171","msg":"trace[1917043006] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"322.298314ms","start":"2026-01-30T09:38:09.91801Z","end":"2026-01-30T09:38:10.240308Z","steps":["trace[1917043006] 'process raft request' (duration: 294.641678ms)","trace[1917043006] 'compare' (duration: 26.727912ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:38:10.240482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:38:09.917994Z","time spent":"322.410228ms","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T09:38:12.944913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.380196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:38:12.945037Z","caller":"traceutil/trace.go:171","msg":"trace[1400605915] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1003; }","duration":"156.57766ms","start":"2026-01-30T09:38:12.788437Z","end":"2026-01-30T09:38:12.945015Z","steps":["trace[1400605915] 'range keys from in-memory index tree' (duration: 156.296123ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:38:12.98059Z","caller":"traceutil/trace.go:171","msg":"trace[43752758] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"109.64422ms","start":"2026-01-30T09:38:12.870914Z","end":"2026-01-30T09:38:12.980558Z","steps":["trace[43752758] 'process raft request' (duration: 109.408184ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:38:13.592844Z","caller":"traceutil/trace.go:171","msg":"trace[1072870971] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"309.118963ms","start":"2026-01-30T09:38:13.283702Z","end":"2026-01-30T09:38:13.592821Z","steps":["trace[1072870971] 'read index received' (duration: 213.920038ms)","trace[1072870971] 'applied index is now lower than readState.Index' (duration: 95.197825ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:38:13.59296Z","caller":"traceutil/trace.go:171","msg":"trace[1872195832] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"359.522226ms","start":"2026-01-30T09:38:13.233425Z","end":"2026-01-30T09:38:13.592948Z","steps":["trace[1872195832] 'process raft request' (duration: 264.185237ms)","trace[1872195832] 'compare' (duration: 94.913807ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T09:38:13.593063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:38:13.233414Z","time spent":"359.590757ms","remote":"127.0.0.1:51826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-30T09:38:13.593259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.556114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T09:38:13.593288Z","caller":"traceutil/trace.go:171","msg":"trace[2092689784] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1012; }","duration":"309.632096ms","start":"2026-01-30T09:38:13.283648Z","end":"2026-01-30T09:38:13.59328Z","steps":["trace[2092689784] 'agreement among raft nodes before linearized reading' (duration: 309.564525ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:38:13.593307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:38:13.283627Z","time spent":"309.675708ms","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-01-30T09:38:16.48312Z","caller":"traceutil/trace.go:171","msg":"trace[319378001] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"113.564105ms","start":"2026-01-30T09:38:16.369532Z","end":"2026-01-30T09:38:16.483096Z","steps":["trace[319378001] 'process raft request' (duration: 58.656242ms)","trace[319378001] 'compare' (duration: 49.988222ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:41:29.19748Z","caller":"traceutil/trace.go:171","msg":"trace[1373669293] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"104.032217ms","start":"2026-01-30T09:41:29.093424Z","end":"2026-01-30T09:41:29.197456Z","steps":["trace[1373669293] 'process raft request' (duration: 103.869433ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T09:41:35.280757Z","caller":"traceutil/trace.go:171","msg":"trace[896384806] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"317.346669ms","start":"2026-01-30T09:41:34.963388Z","end":"2026-01-30T09:41:35.280734Z","steps":["trace[896384806] 'process raft request' (duration: 317.189455ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:41:35.280898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:41:34.96337Z","time spent":"317.454911ms","remote":"127.0.0.1:52030","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-01-30T09:41:35.291359Z","caller":"traceutil/trace.go:171","msg":"trace[1151441769] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1957; }","duration":"195.049981ms","start":"2026-01-30T09:41:35.096298Z","end":"2026-01-30T09:41:35.291348Z","steps":["trace[1151441769] 'read index received' (duration: 185.348516ms)","trace[1151441769] 'applied index is now lower than readState.Index' (duration: 9.700915ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T09:41:35.291728Z","caller":"traceutil/trace.go:171","msg":"trace[1405026496] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"326.094069ms","start":"2026-01-30T09:41:34.965627Z","end":"2026-01-30T09:41:35.291721Z","steps":["trace[1405026496] 'process raft request' (duration: 319.399583ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T09:41:35.291902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T09:41:34.965606Z","time spent":"326.256034ms","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T09:41:35.291818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.517784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-01-30T09:41:35.297801Z","caller":"traceutil/trace.go:171","msg":"trace[1820355888] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1874; }","duration":"201.563543ms","start":"2026-01-30T09:41:35.09621Z","end":"2026-01-30T09:41:35.297774Z","steps":["trace[1820355888] 'agreement among raft nodes before linearized reading' (duration: 195.536165ms)"],"step_count":1}