{"level":"warn","ts":"2026-02-03T10:26:08.765291Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-03T10:26:08.765466Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110: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.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-03T10:26:08.765545Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-03T10:26:08.765556Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-03T10:26:08.765574Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-03T10:26:08.766353Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-02-03T10:26:08.766525Z","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.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-03T10:26:08.771715Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.951583ms"} {"level":"info","ts":"2026-02-03T10:26:08.785328Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-02-03T10:26:08.785388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T10:26:08.785408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-02-03T10:26:08.785439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T10:26:08.785452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-02-03T10:26:08.785482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-02-03T10:26:08.792079Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T10:26:08.796596Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T10:26:08.799564Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-03T10:26:08.803557Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T10:26:08.804921Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-03T10:26:08.804959Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-03T10:26:08.804967Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-03T10:26:08.806853Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T10:26:08.807142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-02-03T10:26:08.807226Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-03T10:26:08.814339Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-03T10:26:08.814577Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T10:26:08.814604Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T10:26:08.814703Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-03T10:26:08.814715Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-03T10:26:09.78642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T10:26:09.786459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T10:26:09.78648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-02-03T10:26:09.786491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-02-03T10:26:09.786497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-03T10:26:09.786505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-02-03T10:26:09.786516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-03T10:26:09.808179Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:26:09.82815Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T10:26:09.828232Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T10:26:09.829995Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-02-03T10:26:09.830321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T10:26:09.830471Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T10:26:09.830485Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T10:26:09.83247Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T10:26:09.851651Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:26:09.873168Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:26:09.873194Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-03T10:26:13.510787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.05548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:13.510894Z","caller":"traceutil/trace.go:171","msg":"trace[1087485057] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:256; }","duration":"145.399302ms","start":"2026-02-03T10:26:13.365479Z","end":"2026-02-03T10:26:13.510878Z","steps":["trace[1087485057] 'read index received' (duration: 13.69928ms)","trace[1087485057] 'applied index is now lower than readState.Index' (duration: 131.698802ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:13.511111Z","caller":"traceutil/trace.go:171","msg":"trace[1737486219] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"148.893718ms","start":"2026-02-03T10:26:13.36208Z","end":"2026-02-03T10:26:13.510974Z","steps":["trace[1737486219] 'process raft request' (duration: 17.120594ms)","trace[1737486219] 'compare' (duration: 130.889427ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:13.511826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.340201ms","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-02-03T10:26:13.511871Z","caller":"traceutil/trace.go:171","msg":"trace[1512941471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:250; }","duration":"146.405802ms","start":"2026-02-03T10:26:13.365454Z","end":"2026-02-03T10:26:13.51186Z","steps":["trace[1512941471] 'agreement among raft nodes before linearized reading' (duration: 146.264268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:13.512261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.734928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-03T10:26:13.512308Z","caller":"traceutil/trace.go:171","msg":"trace[909937375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:250; }","duration":"146.789889ms","start":"2026-02-03T10:26:13.365506Z","end":"2026-02-03T10:26:13.512296Z","steps":["trace[909937375] 'agreement among raft nodes before linearized reading' (duration: 146.720108ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:13.896331Z","caller":"traceutil/trace.go:171","msg":"trace[1065125069] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"122.964857ms","start":"2026-02-03T10:26:13.773343Z","end":"2026-02-03T10:26:13.896308Z","steps":["trace[1065125069] 'process raft request' (duration: 122.835614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:14.621049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644190682420064,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T10:26:14.778681Z","caller":"traceutil/trace.go:171","msg":"trace[1771340805] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:273; }","duration":"657.635084ms","start":"2026-02-03T10:26:14.121018Z","end":"2026-02-03T10:26:14.778653Z","steps":["trace[1771340805] 'read index received' (duration: 657.451121ms)","trace[1771340805] 'applied index is now lower than readState.Index' (duration: 182.613µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:14.779043Z","caller":"traceutil/trace.go:171","msg":"trace[1203512208] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"658.208305ms","start":"2026-02-03T10:26:14.120819Z","end":"2026-02-03T10:26:14.779027Z","steps":["trace[1203512208] 'process raft request' (duration: 657.710225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:14.779217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.136915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-03T10:26:14.779986Z","caller":"traceutil/trace.go:171","msg":"trace[1713533726] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:267; }","duration":"658.960469ms","start":"2026-02-03T10:26:14.121007Z","end":"2026-02-03T10:26:14.779967Z","steps":["trace[1713533726] 'agreement among raft nodes before linearized reading' (duration: 658.081063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:14.780094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:14.121002Z","time spent":"659.076011ms","remote":"127.0.0.1:60400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-03T10:26:14.780425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:14.120799Z","time spent":"658.733906ms","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:26:15.385094Z","caller":"traceutil/trace.go:171","msg":"trace[451850193] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"336.048835ms","start":"2026-02-03T10:26:15.048989Z","end":"2026-02-03T10:26:15.385038Z","steps":["trace[451850193] 'process raft request' (duration: 307.86628ms)","trace[451850193] 'compare' (duration: 27.745137ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:15.385225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:15.048975Z","time spent":"336.183858ms","remote":"127.0.0.1:60662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":526,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:26:15.417271Z","caller":"traceutil/trace.go:171","msg":"trace[829582079] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"366.259198ms","start":"2026-02-03T10:26:15.050997Z","end":"2026-02-03T10:26:15.417256Z","steps":["trace[829582079] 'process raft request' (duration: 366.149986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:15.417369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:15.050987Z","time spent":"366.341669ms","remote":"127.0.0.1:60498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:26:15.532784Z","caller":"traceutil/trace.go:171","msg":"trace[1603078014] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"106.255299ms","start":"2026-02-03T10:26:15.426515Z","end":"2026-02-03T10:26:15.53277Z","steps":["trace[1603078014] 'process raft request' (duration: 105.959324ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:15.550993Z","caller":"traceutil/trace.go:171","msg":"trace[2078839008] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"101.320404ms","start":"2026-02-03T10:26:15.449659Z","end":"2026-02-03T10:26:15.550979Z","steps":["trace[2078839008] 'process raft request' (duration: 101.266713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:15.551593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.865584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T10:26:15.551675Z","caller":"traceutil/trace.go:171","msg":"trace[518274555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:279; }","duration":"101.968396ms","start":"2026-02-03T10:26:15.449697Z","end":"2026-02-03T10:26:15.551666Z","steps":["trace[518274555] 'agreement among raft nodes before linearized reading' (duration: 101.597239ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:15.713031Z","caller":"traceutil/trace.go:171","msg":"trace[894299545] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:288; }","duration":"141.964458ms","start":"2026-02-03T10:26:15.571045Z","end":"2026-02-03T10:26:15.71301Z","steps":["trace[894299545] 'read index received' (duration: 91.591801ms)","trace[894299545] 'applied index is now lower than readState.Index' (duration: 50.371717ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:15.71318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.105571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-03T10:26:15.713211Z","caller":"traceutil/trace.go:171","msg":"trace[1388867266] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:284; }","duration":"142.199953ms","start":"2026-02-03T10:26:15.571001Z","end":"2026-02-03T10:26:15.713201Z","steps":["trace[1388867266] 'agreement among raft nodes before linearized reading' (duration: 142.099541ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:15.713495Z","caller":"traceutil/trace.go:171","msg":"trace[1395339956] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"155.875952ms","start":"2026-02-03T10:26:15.557598Z","end":"2026-02-03T10:26:15.713474Z","steps":["trace[1395339956] 'process raft request' (duration: 105.090437ms)","trace[1395339956] 'compare' (duration: 49.535971ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:15.714731Z","caller":"traceutil/trace.go:171","msg":"trace[949753406] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"154.2454ms","start":"2026-02-03T10:26:15.560473Z","end":"2026-02-03T10:26:15.714719Z","steps":["trace[949753406] 'process raft request' (duration: 152.504887ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:15.71581Z","caller":"traceutil/trace.go:171","msg":"trace[1255135424] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"157.103466ms","start":"2026-02-03T10:26:15.558695Z","end":"2026-02-03T10:26:15.715798Z","steps":["trace[1255135424] 'process raft request' (duration: 154.238732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:16.148085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.822549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:16.148252Z","caller":"traceutil/trace.go:171","msg":"trace[296541659] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"203.341664ms","start":"2026-02-03T10:26:15.944894Z","end":"2026-02-03T10:26:16.148236Z","steps":["trace[296541659] 'process raft request' (duration: 203.278332ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:16.148528Z","caller":"traceutil/trace.go:171","msg":"trace[1699588653] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"205.27635ms","start":"2026-02-03T10:26:15.943242Z","end":"2026-02-03T10:26:16.148518Z","steps":["trace[1699588653] 'compare' (duration: 195.710247ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:16.148638Z","caller":"traceutil/trace.go:171","msg":"trace[1872907971] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:298; }","duration":"205.190399ms","start":"2026-02-03T10:26:15.943439Z","end":"2026-02-03T10:26:16.148629Z","steps":["trace[1872907971] 'read index received' (duration: 8.739416ms)","trace[1872907971] 'applied index is now lower than readState.Index' (duration: 196.450003ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:16.148816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.367392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-03T10:26:16.148846Z","caller":"traceutil/trace.go:171","msg":"trace[37928115] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:293; }","duration":"205.447244ms","start":"2026-02-03T10:26:15.943388Z","end":"2026-02-03T10:26:16.148836Z","steps":["trace[37928115] 'agreement among raft nodes before linearized reading' (duration: 205.371503ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:16.211059Z","caller":"traceutil/trace.go:171","msg":"trace[1372423995] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"179.647002ms","start":"2026-02-03T10:26:16.031389Z","end":"2026-02-03T10:26:16.211036Z","steps":["trace[1372423995] 'process raft request' (duration: 179.54091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:16.218317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.617356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:16.218399Z","caller":"traceutil/trace.go:171","msg":"trace[413402851] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:295; }","duration":"186.740499ms","start":"2026-02-03T10:26:16.031641Z","end":"2026-02-03T10:26:16.218381Z","steps":["trace[413402851] 'agreement among raft nodes before linearized reading' (duration: 186.561694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:16.21841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.83718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-03T10:26:16.218486Z","caller":"traceutil/trace.go:171","msg":"trace[2097020389] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:295; }","duration":"186.933062ms","start":"2026-02-03T10:26:16.031531Z","end":"2026-02-03T10:26:16.218464Z","steps":["trace[2097020389] 'agreement among raft nodes before linearized reading' (duration: 186.81646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:16.21866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.758706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T10:26:16.21869Z","caller":"traceutil/trace.go:171","msg":"trace[184536061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:295; }","duration":"269.802426ms","start":"2026-02-03T10:26:15.948879Z","end":"2026-02-03T10:26:16.218681Z","steps":["trace[184536061] 'agreement among raft nodes before linearized reading' (duration: 269.751435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:16.284873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.140604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:16.284933Z","caller":"traceutil/trace.go:171","msg":"trace[402545154] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:296; }","duration":"128.238185ms","start":"2026-02-03T10:26:16.156682Z","end":"2026-02-03T10:26:16.28492Z","steps":["trace[402545154] 'agreement among raft nodes before linearized reading' (duration: 128.124623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:16.427141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.429214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-02-03T10:26:16.427258Z","caller":"traceutil/trace.go:171","msg":"trace[38119582] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:310; }","duration":"104.562317ms","start":"2026-02-03T10:26:16.32268Z","end":"2026-02-03T10:26:16.427243Z","steps":["trace[38119582] 'agreement among raft nodes before linearized reading' (duration: 63.220521ms)","trace[38119582] 'range keys from in-memory index tree' (duration: 41.208903ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:19.40782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.75315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:19.408282Z","caller":"traceutil/trace.go:171","msg":"trace[2046454153] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"230.00475ms","start":"2026-02-03T10:26:19.178261Z","end":"2026-02-03T10:26:19.408266Z","steps":["trace[2046454153] 'process raft request' (duration: 229.968559ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:19.408863Z","caller":"traceutil/trace.go:171","msg":"trace[771423392] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"278.653974ms","start":"2026-02-03T10:26:19.130193Z","end":"2026-02-03T10:26:19.408847Z","steps":["trace[771423392] 'process raft request' (duration: 141.654971ms)","trace[771423392] 'compare' (duration: 135.637848ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:19.409577Z","caller":"traceutil/trace.go:171","msg":"trace[1367828905] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:370; }","duration":"240.155763ms","start":"2026-02-03T10:26:19.169408Z","end":"2026-02-03T10:26:19.409564Z","steps":["trace[1367828905] 'read index received' (duration: 102.379605ms)","trace[1367828905] 'applied index is now lower than readState.Index' (duration: 137.774428ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:19.411066Z","caller":"traceutil/trace.go:171","msg":"trace[622101674] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"234.043977ms","start":"2026-02-03T10:26:19.177006Z","end":"2026-02-03T10:26:19.41105Z","steps":["trace[622101674] 'process raft request' (duration: 231.07458ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:19.411791Z","caller":"traceutil/trace.go:171","msg":"trace[304917495] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"233.872463ms","start":"2026-02-03T10:26:19.177905Z","end":"2026-02-03T10:26:19.411778Z","steps":["trace[304917495] 'process raft request' (duration: 230.275525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:19.412418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.997637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-03T10:26:19.412487Z","caller":"traceutil/trace.go:171","msg":"trace[1866906552] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:365; }","duration":"243.100679ms","start":"2026-02-03T10:26:19.169375Z","end":"2026-02-03T10:26:19.412475Z","steps":["trace[1866906552] 'agreement among raft nodes before linearized reading' (duration: 242.990187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:19.412952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.037779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-03T10:26:19.413038Z","caller":"traceutil/trace.go:171","msg":"trace[1122034154] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:365; }","duration":"241.114641ms","start":"2026-02-03T10:26:19.17189Z","end":"2026-02-03T10:26:19.413004Z","steps":["trace[1122034154] 'agreement among raft nodes before linearized reading' (duration: 241.023249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:19.414489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.989943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-03T10:26:19.41454Z","caller":"traceutil/trace.go:171","msg":"trace[979493662] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:365; }","duration":"146.067685ms","start":"2026-02-03T10:26:19.268462Z","end":"2026-02-03T10:26:19.414529Z","steps":["trace[979493662] 'agreement among raft nodes before linearized reading' (duration: 145.977824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:19.414668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.048286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-03T10:26:19.414682Z","caller":"traceutil/trace.go:171","msg":"trace[1256214133] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:365; }","duration":"195.083666ms","start":"2026-02-03T10:26:19.219593Z","end":"2026-02-03T10:26:19.414677Z","steps":["trace[1256214133] 'agreement among raft nodes before linearized reading' (duration: 195.055656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:19.806779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.703856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:19.809648Z","caller":"traceutil/trace.go:171","msg":"trace[673285240] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"383.123419ms","start":"2026-02-03T10:26:19.426498Z","end":"2026-02-03T10:26:19.809622Z","steps":["trace[673285240] 'process raft request' (duration: 192.504437ms)","trace[673285240] 'compare' (duration: 187.590694ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:19.809886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:19.42648Z","time spent":"383.329663ms","remote":"127.0.0.1:60486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:19.81902Z","caller":"traceutil/trace.go:171","msg":"trace[2130197037] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"389.076872ms","start":"2026-02-03T10:26:19.429933Z","end":"2026-02-03T10:26:19.81901Z","steps":["trace[2130197037] 'process raft request' (duration: 388.902269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:19.82041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:19.429864Z","time spent":"390.515469ms","remote":"127.0.0.1:60646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:19.819464Z","caller":"traceutil/trace.go:171","msg":"trace[761998478] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"387.216487ms","start":"2026-02-03T10:26:19.432229Z","end":"2026-02-03T10:26:19.819445Z","steps":["trace[761998478] 'process raft request' (duration: 386.676576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:19.820812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:19.432222Z","time spent":"388.531472ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:26:19.819642Z","caller":"traceutil/trace.go:171","msg":"trace[727491343] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"387.37747ms","start":"2026-02-03T10:26:19.432229Z","end":"2026-02-03T10:26:19.819606Z","steps":["trace[727491343] 'process raft request' (duration: 386.90474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:19.821142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:19.432219Z","time spent":"388.902229ms","remote":"127.0.0.1:60486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:26:19.819767Z","caller":"traceutil/trace.go:171","msg":"trace[1769328365] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"387.470112ms","start":"2026-02-03T10:26:19.432285Z","end":"2026-02-03T10:26:19.819755Z","steps":["trace[1769328365] 'process raft request' (duration: 387.024133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:19.821559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:19.43228Z","time spent":"389.220105ms","remote":"127.0.0.1:60646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:19.819854Z","caller":"traceutil/trace.go:171","msg":"trace[102954484] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"386.968362ms","start":"2026-02-03T10:26:19.432878Z","end":"2026-02-03T10:26:19.819847Z","steps":["trace[102954484] 'process raft request' (duration: 386.479773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:19.822378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:19.43287Z","time spent":"389.48342ms","remote":"127.0.0.1:60814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2125,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:26:20.005096Z","caller":"traceutil/trace.go:171","msg":"trace[1336581176] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"173.986205ms","start":"2026-02-03T10:26:19.831085Z","end":"2026-02-03T10:26:20.005071Z","steps":["trace[1336581176] 'process raft request' (duration: 89.322857ms)","trace[1336581176] 'compare' (duration: 84.566076ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:20.053407Z","caller":"traceutil/trace.go:171","msg":"trace[854552021] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"218.594663ms","start":"2026-02-03T10:26:19.834796Z","end":"2026-02-03T10:26:20.053391Z","steps":["trace[854552021] 'process raft request' (duration: 218.551022ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.053709Z","caller":"traceutil/trace.go:171","msg":"trace[1792085857] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"219.071853ms","start":"2026-02-03T10:26:19.83461Z","end":"2026-02-03T10:26:20.053682Z","steps":["trace[1792085857] 'process raft request' (duration: 218.490772ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.053822Z","caller":"traceutil/trace.go:171","msg":"trace[801545308] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"219.066953ms","start":"2026-02-03T10:26:19.834749Z","end":"2026-02-03T10:26:20.053816Z","steps":["trace[801545308] 'process raft request' (duration: 218.568854ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.053746Z","caller":"traceutil/trace.go:171","msg":"trace[1442944793] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"219.030902ms","start":"2026-02-03T10:26:19.834708Z","end":"2026-02-03T10:26:20.053739Z","steps":["trace[1442944793] 'process raft request' (duration: 218.581254ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.054112Z","caller":"traceutil/trace.go:171","msg":"trace[1844502354] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"212.304573ms","start":"2026-02-03T10:26:19.841795Z","end":"2026-02-03T10:26:20.054099Z","steps":["trace[1844502354] 'process raft request' (duration: 211.5771ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.054495Z","caller":"traceutil/trace.go:171","msg":"trace[1805774450] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"212.519757ms","start":"2026-02-03T10:26:19.841964Z","end":"2026-02-03T10:26:20.054484Z","steps":["trace[1805774450] 'process raft request' (duration: 212.070069ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.054701Z","caller":"traceutil/trace.go:171","msg":"trace[1182940170] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"210.48276ms","start":"2026-02-03T10:26:19.844212Z","end":"2026-02-03T10:26:20.054695Z","steps":["trace[1182940170] 'process raft request' (duration: 210.465769ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.054877Z","caller":"traceutil/trace.go:171","msg":"trace[911048689] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"212.857234ms","start":"2026-02-03T10:26:19.842014Z","end":"2026-02-03T10:26:20.054871Z","steps":["trace[911048689] 'process raft request' (duration: 212.433005ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.055092Z","caller":"traceutil/trace.go:171","msg":"trace[1689310450] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"212.377096ms","start":"2026-02-03T10:26:19.842702Z","end":"2026-02-03T10:26:20.055079Z","steps":["trace[1689310450] 'process raft request' (duration: 211.907697ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.055343Z","caller":"traceutil/trace.go:171","msg":"trace[1111803294] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"211.735922ms","start":"2026-02-03T10:26:19.843601Z","end":"2026-02-03T10:26:20.055337Z","steps":["trace[1111803294] 'process raft request' (duration: 211.05476ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.209645Z","caller":"traceutil/trace.go:171","msg":"trace[560722174] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"199.032372ms","start":"2026-02-03T10:26:20.010583Z","end":"2026-02-03T10:26:20.209615Z","steps":["trace[560722174] 'process raft request' (duration: 186.223528ms)","trace[560722174] 'compare' (duration: 12.58586ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:20.258724Z","caller":"traceutil/trace.go:171","msg":"trace[841890326] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:391; }","duration":"173.831023ms","start":"2026-02-03T10:26:20.084876Z","end":"2026-02-03T10:26:20.258707Z","steps":["trace[841890326] 'read index received' (duration: 112.022049ms)","trace[841890326] 'applied index is now lower than readState.Index' (duration: 61.808274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:20.259052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.15356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-03T10:26:20.259118Z","caller":"traceutil/trace.go:171","msg":"trace[1282761471] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:389; }","duration":"174.249251ms","start":"2026-02-03T10:26:20.084854Z","end":"2026-02-03T10:26:20.259103Z","steps":["trace[1282761471] 'agreement among raft nodes before linearized reading' (duration: 174.069347ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.259226Z","caller":"traceutil/trace.go:171","msg":"trace[934199462] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"189.860327ms","start":"2026-02-03T10:26:20.069357Z","end":"2026-02-03T10:26:20.259217Z","steps":["trace[934199462] 'process raft request' (duration: 189.094802ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.259408Z","caller":"traceutil/trace.go:171","msg":"trace[1180404297] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"189.293847ms","start":"2026-02-03T10:26:20.070109Z","end":"2026-02-03T10:26:20.259403Z","steps":["trace[1180404297] 'process raft request' (duration: 188.45184ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.259554Z","caller":"traceutil/trace.go:171","msg":"trace[1633374242] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"189.253125ms","start":"2026-02-03T10:26:20.070291Z","end":"2026-02-03T10:26:20.259544Z","steps":["trace[1633374242] 'process raft request' (duration: 188.340508ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.259758Z","caller":"traceutil/trace.go:171","msg":"trace[263284224] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"188.522442ms","start":"2026-02-03T10:26:20.07123Z","end":"2026-02-03T10:26:20.259752Z","steps":["trace[263284224] 'process raft request' (duration: 187.462642ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.259908Z","caller":"traceutil/trace.go:171","msg":"trace[1097565636] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"188.748746ms","start":"2026-02-03T10:26:20.071154Z","end":"2026-02-03T10:26:20.259903Z","steps":["trace[1097565636] 'process raft request' (duration: 187.505942ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:20.26006Z","caller":"traceutil/trace.go:171","msg":"trace[1554355538] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"188.855669ms","start":"2026-02-03T10:26:20.071198Z","end":"2026-02-03T10:26:20.260053Z","steps":["trace[1554355538] 'process raft request' (duration: 187.482113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:20.657419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.146417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:20.657543Z","caller":"traceutil/trace.go:171","msg":"trace[1599604948] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"440.205093ms","start":"2026-02-03T10:26:20.217295Z","end":"2026-02-03T10:26:20.6575Z","steps":["trace[1599604948] 'process raft request' (duration: 263.917164ms)","trace[1599604948] 'compare' (duration: 175.462704ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:20.657606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:20.217266Z","time spent":"440.318195ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:26:20.789192Z","caller":"traceutil/trace.go:171","msg":"trace[385317872] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:398; }","duration":"506.605205ms","start":"2026-02-03T10:26:20.282564Z","end":"2026-02-03T10:26:20.789169Z","steps":["trace[385317872] 'read index received' (duration: 198.664225ms)","trace[385317872] 'applied index is now lower than readState.Index' (duration: 307.94003ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:20.789526Z","caller":"traceutil/trace.go:171","msg":"trace[1627139503] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"512.972326ms","start":"2026-02-03T10:26:20.27654Z","end":"2026-02-03T10:26:20.789512Z","steps":["trace[1627139503] 'process raft request' (duration: 482.798533ms)","trace[1627139503] 'compare' (duration: 29.450529ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:20.789732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:20.276519Z","time spent":"513.137299ms","remote":"127.0.0.1:60486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4053,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:20.789907Z","caller":"traceutil/trace.go:171","msg":"trace[1002959280] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"511.621611ms","start":"2026-02-03T10:26:20.278274Z","end":"2026-02-03T10:26:20.789895Z","steps":["trace[1002959280] 'process raft request' (duration: 510.799925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:20.789989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:20.278265Z","time spent":"511.664051ms","remote":"127.0.0.1:60486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3401,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:20.790306Z","caller":"traceutil/trace.go:171","msg":"trace[1091697867] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"507.776177ms","start":"2026-02-03T10:26:20.282411Z","end":"2026-02-03T10:26:20.790187Z","steps":["trace[1091697867] 'process raft request' (duration: 506.713057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:20.79037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:20.282405Z","time spent":"507.93172ms","remote":"127.0.0.1:60810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:26:20.790547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.953651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2817"} {"level":"info","ts":"2026-02-03T10:26:20.79058Z","caller":"traceutil/trace.go:171","msg":"trace[1842132213] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:393; }","duration":"508.049752ms","start":"2026-02-03T10:26:20.282521Z","end":"2026-02-03T10:26:20.79057Z","steps":["trace[1842132213] 'agreement among raft nodes before linearized reading' (duration: 507.96076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:20.790614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:20.282501Z","time spent":"508.105464ms","remote":"127.0.0.1:60798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2840,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-02-03T10:26:20.790798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.94261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T10:26:20.790829Z","caller":"traceutil/trace.go:171","msg":"trace[75006227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:393; }","duration":"487.975211ms","start":"2026-02-03T10:26:20.302845Z","end":"2026-02-03T10:26:20.79082Z","steps":["trace[75006227] 'agreement among raft nodes before linearized reading' (duration: 487.893649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:20.790856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:20.302818Z","time spent":"488.032031ms","remote":"127.0.0.1:60564","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-02-03T10:26:20.959335Z","caller":"traceutil/trace.go:171","msg":"trace[37176048] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"111.981287ms","start":"2026-02-03T10:26:20.847323Z","end":"2026-02-03T10:26:20.959304Z","steps":["trace[37176048] 'process raft request' (duration: 94.893902ms)","trace[37176048] 'compare' (duration: 16.983443ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:21.603903Z","caller":"traceutil/trace.go:171","msg":"trace[1690764994] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"208.246766ms","start":"2026-02-03T10:26:21.395641Z","end":"2026-02-03T10:26:21.603888Z","steps":["trace[1690764994] 'process raft request' (duration: 207.998732ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:29.64879Z","caller":"traceutil/trace.go:171","msg":"trace[1111106040] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"294.127119ms","start":"2026-02-03T10:26:29.354636Z","end":"2026-02-03T10:26:29.648763Z","steps":["trace[1111106040] 'process raft request' (duration: 266.343421ms)","trace[1111106040] 'compare' (duration: 27.542494ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:29.649185Z","caller":"traceutil/trace.go:171","msg":"trace[196167685] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"294.478316ms","start":"2026-02-03T10:26:29.354692Z","end":"2026-02-03T10:26:29.649171Z","steps":["trace[196167685] 'process raft request' (duration: 294.028477ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:29.649256Z","caller":"traceutil/trace.go:171","msg":"trace[669299870] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"204.193861ms","start":"2026-02-03T10:26:29.44505Z","end":"2026-02-03T10:26:29.649244Z","steps":["trace[669299870] 'process raft request' (duration: 203.961727ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:29.649323Z","caller":"traceutil/trace.go:171","msg":"trace[1314250876] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"202.925247ms","start":"2026-02-03T10:26:29.446391Z","end":"2026-02-03T10:26:29.649317Z","steps":["trace[1314250876] 'process raft request' (duration: 202.635892ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:29.649484Z","caller":"traceutil/trace.go:171","msg":"trace[1900537134] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"205.23523ms","start":"2026-02-03T10:26:29.44424Z","end":"2026-02-03T10:26:29.649475Z","steps":["trace[1900537134] 'process raft request' (duration: 204.70012ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:29.64954Z","caller":"traceutil/trace.go:171","msg":"trace[70482871] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"204.516237ms","start":"2026-02-03T10:26:29.445017Z","end":"2026-02-03T10:26:29.649534Z","steps":["trace[70482871] 'process raft request' (duration: 203.970617ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:29.968542Z","caller":"traceutil/trace.go:171","msg":"trace[712014233] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"125.718638ms","start":"2026-02-03T10:26:29.842803Z","end":"2026-02-03T10:26:29.968522Z","steps":["trace[712014233] 'process raft request' (duration: 124.392083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:30.331015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.388233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:30.33123Z","caller":"traceutil/trace.go:171","msg":"trace[925745011] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:461; }","duration":"206.529884ms","start":"2026-02-03T10:26:30.124689Z","end":"2026-02-03T10:26:30.331219Z","steps":["trace[925745011] 'read index received' (duration: 51.745923ms)","trace[925745011] 'applied index is now lower than readState.Index' (duration: 154.783381ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:30.331421Z","caller":"traceutil/trace.go:171","msg":"trace[136641091] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"274.639078ms","start":"2026-02-03T10:26:30.056775Z","end":"2026-02-03T10:26:30.331414Z","steps":["trace[136641091] 'process raft request' (duration: 119.706755ms)","trace[136641091] 'compare' (duration: 153.901694ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:30.331582Z","caller":"traceutil/trace.go:171","msg":"trace[212758768] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"274.743111ms","start":"2026-02-03T10:26:30.056833Z","end":"2026-02-03T10:26:30.331576Z","steps":["trace[212758768] 'process raft request' (duration: 274.250331ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:30.331712Z","caller":"traceutil/trace.go:171","msg":"trace[904871624] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"272.827464ms","start":"2026-02-03T10:26:30.058879Z","end":"2026-02-03T10:26:30.331706Z","steps":["trace[904871624] 'process raft request' (duration: 272.252402ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:30.331939Z","caller":"traceutil/trace.go:171","msg":"trace[869344773] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"272.954267ms","start":"2026-02-03T10:26:30.058977Z","end":"2026-02-03T10:26:30.331931Z","steps":["trace[869344773] 'process raft request' (duration: 272.191522ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:30.332176Z","caller":"traceutil/trace.go:171","msg":"trace[1551817219] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"272.849214ms","start":"2026-02-03T10:26:30.059321Z","end":"2026-02-03T10:26:30.33217Z","steps":["trace[1551817219] 'process raft request' (duration: 271.870855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:30.332305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.631895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-03T10:26:30.332321Z","caller":"traceutil/trace.go:171","msg":"trace[1217967220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:454; }","duration":"207.683486ms","start":"2026-02-03T10:26:30.124632Z","end":"2026-02-03T10:26:30.332316Z","steps":["trace[1217967220] 'agreement among raft nodes before linearized reading' (duration: 207.648485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:30.332554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.04279ms","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-02-03T10:26:30.33259Z","caller":"traceutil/trace.go:171","msg":"trace[1524800567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:454; }","duration":"121.109431ms","start":"2026-02-03T10:26:30.211476Z","end":"2026-02-03T10:26:30.332585Z","steps":["trace[1524800567] 'agreement among raft nodes before linearized reading' (duration: 121.042979ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:40.525269Z","caller":"traceutil/trace.go:171","msg":"trace[466439445] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"108.485122ms","start":"2026-02-03T10:26:40.416767Z","end":"2026-02-03T10:26:40.525252Z","steps":["trace[466439445] 'process raft request' (duration: 108.222237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:46.157276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.646704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:46.157388Z","caller":"traceutil/trace.go:171","msg":"trace[711291956] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"461.209884ms","start":"2026-02-03T10:26:45.696165Z","end":"2026-02-03T10:26:46.157375Z","steps":["trace[711291956] 'process raft request' (duration: 133.392355ms)","trace[711291956] 'compare' (duration: 327.487792ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:46.157431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:45.69615Z","time spent":"461.260834ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:46.564337Z","caller":"traceutil/trace.go:171","msg":"trace[182113959] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"707.126985ms","start":"2026-02-03T10:26:45.857196Z","end":"2026-02-03T10:26:46.564323Z","steps":["trace[182113959] 'process raft request' (duration: 698.958999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:46.564411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:45.857173Z","time spent":"707.202547ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:26:46.573712Z","caller":"traceutil/trace.go:171","msg":"trace[1138307303] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:572; }","duration":"317.37127ms","start":"2026-02-03T10:26:46.256324Z","end":"2026-02-03T10:26:46.573695Z","steps":["trace[1138307303] 'read index received' (duration: 299.837837ms)","trace[1138307303] 'applied index is now lower than readState.Index' (duration: 17.532473ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:46.573969Z","caller":"traceutil/trace.go:171","msg":"trace[961247173] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"516.32419ms","start":"2026-02-03T10:26:46.057637Z","end":"2026-02-03T10:26:46.573961Z","steps":["trace[961247173] 'process raft request' (duration: 515.861871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:46.574035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:46.057613Z","time spent":"516.38084ms","remote":"127.0.0.1:60332","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-02-03T10:26:46.574148Z","caller":"traceutil/trace.go:171","msg":"trace[1214933967] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"406.37063ms","start":"2026-02-03T10:26:46.16777Z","end":"2026-02-03T10:26:46.574141Z","steps":["trace[1214933967] 'process raft request' (duration: 405.8372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:46.574213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:46.167751Z","time spent":"406.431432ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:46.574293Z","caller":"traceutil/trace.go:171","msg":"trace[1777603003] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"114.694489ms","start":"2026-02-03T10:26:46.459593Z","end":"2026-02-03T10:26:46.574287Z","steps":["trace[1777603003] 'process raft request' (duration: 114.077747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:46.574377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.050732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:46.574418Z","caller":"traceutil/trace.go:171","msg":"trace[1077670557] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:561; }","duration":"318.105944ms","start":"2026-02-03T10:26:46.256289Z","end":"2026-02-03T10:26:46.574395Z","steps":["trace[1077670557] 'agreement among raft nodes before linearized reading' (duration: 318.068643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:46.574442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:46.256274Z","time spent":"318.163504ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-03T10:26:47.352129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.373608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:47.3522Z","caller":"traceutil/trace.go:171","msg":"trace[249076107] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"367.767687ms","start":"2026-02-03T10:26:46.984421Z","end":"2026-02-03T10:26:47.352189Z","steps":["trace[249076107] 'read index received' (duration: 36.731µs)","trace[249076107] 'applied index is now lower than readState.Index' (duration: 367.730246ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:47.352341Z","caller":"traceutil/trace.go:171","msg":"trace[407581628] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"397.218207ms","start":"2026-02-03T10:26:46.955113Z","end":"2026-02-03T10:26:47.352331Z","steps":["trace[407581628] 'process raft request' (duration: 17.573124ms)","trace[407581628] 'compare' (duration: 379.151514ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:47.35238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:46.955097Z","time spent":"397.262848ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:26:47.352575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.135094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T10:26:47.352597Z","caller":"traceutil/trace.go:171","msg":"trace[396355769] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"368.215156ms","start":"2026-02-03T10:26:46.984375Z","end":"2026-02-03T10:26:47.35259Z","steps":["trace[396355769] 'agreement among raft nodes before linearized reading' (duration: 368.104174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:47.352618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:46.984356Z","time spent":"368.256266ms","remote":"127.0.0.1:60564","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-02-03T10:26:47.398534Z","caller":"traceutil/trace.go:171","msg":"trace[1832531915] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"412.511588ms","start":"2026-02-03T10:26:46.986008Z","end":"2026-02-03T10:26:47.39852Z","steps":["trace[1832531915] 'process raft request' (duration: 412.434946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:47.39862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:46.985994Z","time spent":"412.582399ms","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:26:47.398786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.216501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-03T10:26:47.398866Z","caller":"traceutil/trace.go:171","msg":"trace[1201862859] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:573; }","duration":"342.331774ms","start":"2026-02-03T10:26:47.056519Z","end":"2026-02-03T10:26:47.398851Z","steps":["trace[1201862859] 'agreement among raft nodes before linearized reading' (duration: 342.098409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:47.398899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:47.056511Z","time spent":"342.381205ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-03T10:26:47.39892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.489799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-03T10:26:47.398944Z","caller":"traceutil/trace.go:171","msg":"trace[1647901305] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:573; }","duration":"130.534489ms","start":"2026-02-03T10:26:47.268401Z","end":"2026-02-03T10:26:47.398936Z","steps":["trace[1647901305] 'agreement among raft nodes before linearized reading' (duration: 130.450208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:53.443769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.562294ms","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-02-03T10:26:53.443845Z","caller":"traceutil/trace.go:171","msg":"trace[889755487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:596; }","duration":"547.714307ms","start":"2026-02-03T10:26:52.896115Z","end":"2026-02-03T10:26:53.44383Z","steps":["trace[889755487] 'range keys from in-memory index tree' (duration: 547.407181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:53.443879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:52.896096Z","time spent":"547.774329ms","remote":"127.0.0.1:60564","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-02-03T10:26:55.089216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644190682420772,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:26:55.344297Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.146731797s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T10:26:55.344657Z","caller":"traceutil/trace.go:171","msg":"trace[844667980] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"756.266208ms","start":"2026-02-03T10:26:54.588371Z","end":"2026-02-03T10:26:55.344637Z","steps":["trace[844667980] 'read index received' (duration: 756.058854ms)","trace[844667980] 'applied index is now lower than readState.Index' (duration: 206.584µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:55.344741Z","caller":"traceutil/trace.go:171","msg":"trace[533482826] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.147260376s","start":"2026-02-03T10:26:54.197467Z","end":"2026-02-03T10:26:55.344727Z","steps":["trace[533482826] 'process raft request' (duration: 1.147021342s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:55.344845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:54.197455Z","time spent":"1.147317908s","remote":"127.0.0.1:60486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:26:55.344977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.602955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:55.345004Z","caller":"traceutil/trace.go:171","msg":"trace[880660040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"756.674046ms","start":"2026-02-03T10:26:54.588321Z","end":"2026-02-03T10:26:55.344995Z","steps":["trace[880660040] 'agreement among raft nodes before linearized reading' (duration: 756.628815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:55.34503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:54.588305Z","time spent":"756.718807ms","remote":"127.0.0.1:60298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T10:26:55.345287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.204463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T10:26:55.345317Z","caller":"traceutil/trace.go:171","msg":"trace[414130127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"630.237044ms","start":"2026-02-03T10:26:54.715072Z","end":"2026-02-03T10:26:55.345309Z","steps":["trace[414130127] 'agreement among raft nodes before linearized reading' (duration: 630.104102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:55.345342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:54.71502Z","time spent":"630.316036ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T10:26:55.345548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.578528ms","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-02-03T10:26:55.345579Z","caller":"traceutil/trace.go:171","msg":"trace[614472554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"573.625599ms","start":"2026-02-03T10:26:54.771946Z","end":"2026-02-03T10:26:55.345572Z","steps":["trace[614472554] 'agreement among raft nodes before linearized reading' (duration: 573.547737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:55.345604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:54.771939Z","time spent":"573.659319ms","remote":"127.0.0.1:60564","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-02-03T10:26:55.345776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.825282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T10:26:55.345812Z","caller":"traceutil/trace.go:171","msg":"trace[1621237561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"582.859523ms","start":"2026-02-03T10:26:54.762941Z","end":"2026-02-03T10:26:55.3458Z","steps":["trace[1621237561] 'agreement among raft nodes before linearized reading' (duration: 582.781552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:55.34584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:54.76287Z","time spent":"582.964296ms","remote":"127.0.0.1:60564","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-02-03T10:27:05.61334Z","caller":"traceutil/trace.go:171","msg":"trace[1463841189] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"148.824047ms","start":"2026-02-03T10:27:05.464503Z","end":"2026-02-03T10:27:05.613327Z","steps":["trace[1463841189] 'read index received' (duration: 148.671474ms)","trace[1463841189] 'applied index is now lower than readState.Index' (duration: 152.043µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:27:05.61352Z","caller":"traceutil/trace.go:171","msg":"trace[1329428142] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"191.522829ms","start":"2026-02-03T10:27:05.421992Z","end":"2026-02-03T10:27:05.613515Z","steps":["trace[1329428142] 'process raft request' (duration: 191.224873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:05.613693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.174545ms","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-02-03T10:27:05.61372Z","caller":"traceutil/trace.go:171","msg":"trace[236804778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"149.229016ms","start":"2026-02-03T10:27:05.464484Z","end":"2026-02-03T10:27:05.613713Z","steps":["trace[236804778] 'agreement among raft nodes before linearized reading' (duration: 149.133504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:05.613845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.171961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T10:27:05.613867Z","caller":"traceutil/trace.go:171","msg":"trace[1200382329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:664; }","duration":"143.203751ms","start":"2026-02-03T10:27:05.470658Z","end":"2026-02-03T10:27:05.613862Z","steps":["trace[1200382329] 'agreement among raft nodes before linearized reading' (duration: 143.15087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:05.614302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.310299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T10:27:05.614327Z","caller":"traceutil/trace.go:171","msg":"trace[2114676133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:664; }","duration":"126.34595ms","start":"2026-02-03T10:27:05.487976Z","end":"2026-02-03T10:27:05.614322Z","steps":["trace[2114676133] 'agreement among raft nodes before linearized reading' (duration: 126.293769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:08.608491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.392158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:27:08.608558Z","caller":"traceutil/trace.go:171","msg":"trace[1893170136] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"286.756579ms","start":"2026-02-03T10:27:08.321792Z","end":"2026-02-03T10:27:08.608549Z","steps":["trace[1893170136] 'read index received' (duration: 17.911µs)","trace[1893170136] 'applied index is now lower than readState.Index' (duration: 286.738118ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:08.608661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.865861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-skfc5\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-03T10:27:08.608674Z","caller":"traceutil/trace.go:171","msg":"trace[10848917] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-skfc5; range_end:; response_count:1; response_revision:697; }","duration":"286.905691ms","start":"2026-02-03T10:27:08.321764Z","end":"2026-02-03T10:27:08.608669Z","steps":["trace[10848917] 'agreement among raft nodes before linearized reading' (duration: 286.809549ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:08.608729Z","caller":"traceutil/trace.go:171","msg":"trace[78391029] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"362.913334ms","start":"2026-02-03T10:27:08.245784Z","end":"2026-02-03T10:27:08.608697Z","steps":["trace[78391029] 'process raft request' (duration: 72.272932ms)","trace[78391029] 'compare' (duration: 290.311905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:08.608879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:08.245771Z","time spent":"363.029027ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:27:08.851601Z","caller":"traceutil/trace.go:171","msg":"trace[132222987] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"166.747569ms","start":"2026-02-03T10:27:08.684836Z","end":"2026-02-03T10:27:08.851583Z","steps":["trace[132222987] 'process raft request' (duration: 166.643027ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:09.454987Z","caller":"traceutil/trace.go:171","msg":"trace[585750163] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"109.417557ms","start":"2026-02-03T10:27:09.345553Z","end":"2026-02-03T10:27:09.454971Z","steps":["trace[585750163] 'process raft request' (duration: 74.005175ms)","trace[585750163] 'compare' (duration: 35.2901ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:27:09.455104Z","caller":"traceutil/trace.go:171","msg":"trace[630886484] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"108.310426ms","start":"2026-02-03T10:27:09.346789Z","end":"2026-02-03T10:27:09.4551Z","steps":["trace[630886484] 'process raft request' (duration: 108.153864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:22.184899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644190682421113,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T10:27:22.398842Z","caller":"traceutil/trace.go:171","msg":"trace[1880549580] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"714.1794ms","start":"2026-02-03T10:27:21.684643Z","end":"2026-02-03T10:27:22.398823Z","steps":["trace[1880549580] 'read index received' (duration: 714.047238ms)","trace[1880549580] 'applied index is now lower than readState.Index' (duration: 131.482µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:27:22.399066Z","caller":"traceutil/trace.go:171","msg":"trace[481214769] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"714.525126ms","start":"2026-02-03T10:27:21.684533Z","end":"2026-02-03T10:27:22.399058Z","steps":["trace[481214769] 'process raft request' (duration: 714.20377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:22.399154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:21.684522Z","time spent":"714.570967ms","remote":"127.0.0.1:60564","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-02-03T10:27:22.399329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.682259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T10:27:22.399357Z","caller":"traceutil/trace.go:171","msg":"trace[1181311531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"714.72065ms","start":"2026-02-03T10:27:21.68463Z","end":"2026-02-03T10:27:22.399351Z","steps":["trace[1181311531] 'agreement among raft nodes before linearized reading' (duration: 714.635438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:22.399393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:21.684625Z","time spent":"714.76394ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T10:27:22.399508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.456075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T10:27:22.39953Z","caller":"traceutil/trace.go:171","msg":"trace[1863275868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"703.488016ms","start":"2026-02-03T10:27:21.696037Z","end":"2026-02-03T10:27:22.399525Z","steps":["trace[1863275868] 'agreement among raft nodes before linearized reading' (duration: 703.435365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:22.399547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:21.696029Z","time spent":"703.514286ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:27:22.399679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.627291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:22.399702Z","caller":"traceutil/trace.go:171","msg":"trace[1534264443] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:778; }","duration":"376.679062ms","start":"2026-02-03T10:27:22.023017Z","end":"2026-02-03T10:27:22.399696Z","steps":["trace[1534264443] 'agreement among raft nodes before linearized reading' (duration: 376.642571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:22.399724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:22.023002Z","time spent":"376.716433ms","remote":"127.0.0.1:60594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:27:22.399839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.431282ms","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-02-03T10:27:22.39986Z","caller":"traceutil/trace.go:171","msg":"trace[814671984] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"687.460252ms","start":"2026-02-03T10:27:21.712394Z","end":"2026-02-03T10:27:22.399855Z","steps":["trace[814671984] 'agreement among raft nodes before linearized reading' (duration: 687.408431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:22.39988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:21.71239Z","time spent":"687.486323ms","remote":"127.0.0.1:60564","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-02-03T10:27:22.399979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.771776ms","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-02-03T10:27:22.399999Z","caller":"traceutil/trace.go:171","msg":"trace[1780767174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"689.801477ms","start":"2026-02-03T10:27:21.710192Z","end":"2026-02-03T10:27:22.399994Z","steps":["trace[1780767174] 'agreement among raft nodes before linearized reading' (duration: 689.748746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:22.400015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:21.710187Z","time spent":"689.824218ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-03T10:27:23.129445Z","caller":"traceutil/trace.go:171","msg":"trace[243723997] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"722.762484ms","start":"2026-02-03T10:27:22.406663Z","end":"2026-02-03T10:27:23.129426Z","steps":["trace[243723997] 'process raft request' (duration: 722.657902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:23.12958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:22.406651Z","time spent":"722.854835ms","remote":"127.0.0.1:60564","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-02-03T10:27:23.14335Z","caller":"traceutil/trace.go:171","msg":"trace[1969415726] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:803; }","duration":"439.159779ms","start":"2026-02-03T10:27:22.704172Z","end":"2026-02-03T10:27:23.143332Z","steps":["trace[1969415726] 'read index received' (duration: 425.875056ms)","trace[1969415726] 'applied index is now lower than readState.Index' (duration: 13.283983ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:23.143526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.346412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T10:27:23.143564Z","caller":"traceutil/trace.go:171","msg":"trace[1252449528] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:783; }","duration":"439.421423ms","start":"2026-02-03T10:27:22.704134Z","end":"2026-02-03T10:27:23.143555Z","steps":["trace[1252449528] 'agreement among raft nodes before linearized reading' (duration: 439.26327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:23.143599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:22.704116Z","time spent":"439.476524ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.110\" "} {"level":"info","ts":"2026-02-03T10:27:23.143879Z","caller":"traceutil/trace.go:171","msg":"trace[2063160966] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"737.044513ms","start":"2026-02-03T10:27:22.406811Z","end":"2026-02-03T10:27:23.143855Z","steps":["trace[2063160966] 'process raft request' (duration: 736.35172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:23.143946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:22.406806Z","time spent":"737.104754ms","remote":"127.0.0.1:60564","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-02-03T10:27:23.144075Z","caller":"traceutil/trace.go:171","msg":"trace[700096294] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"737.139645ms","start":"2026-02-03T10:27:22.406927Z","end":"2026-02-03T10:27:23.144066Z","steps":["trace[700096294] 'process raft request' (duration: 736.30507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:23.144129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:22.406899Z","time spent":"737.204717ms","remote":"127.0.0.1:60564","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-02-03T10:27:23.144241Z","caller":"traceutil/trace.go:171","msg":"trace[37571233] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"735.072496ms","start":"2026-02-03T10:27:22.40916Z","end":"2026-02-03T10:27:23.144232Z","steps":["trace[37571233] 'process raft request' (duration: 734.107779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:23.144291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:22.409152Z","time spent":"735.115517ms","remote":"127.0.0.1:60564","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-02-03T10:27:33.277871Z","caller":"traceutil/trace.go:171","msg":"trace[1317651925] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"113.703529ms","start":"2026-02-03T10:27:33.164147Z","end":"2026-02-03T10:27:33.27785Z","steps":["trace[1317651925] 'process raft request' (duration: 113.576507ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:33.394637Z","caller":"traceutil/trace.go:171","msg":"trace[486474930] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"111.631199ms","start":"2026-02-03T10:27:33.282985Z","end":"2026-02-03T10:27:33.394616Z","steps":["trace[486474930] 'process raft request' (duration: 111.521267ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:33.406086Z","caller":"traceutil/trace.go:171","msg":"trace[101132366] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"121.511206ms","start":"2026-02-03T10:27:33.284562Z","end":"2026-02-03T10:27:33.406074Z","steps":["trace[101132366] 'process raft request' (duration: 121.23445ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:33.406228Z","caller":"traceutil/trace.go:171","msg":"trace[347291100] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"120.419266ms","start":"2026-02-03T10:27:33.285802Z","end":"2026-02-03T10:27:33.406221Z","steps":["trace[347291100] 'process raft request' (duration: 120.061899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:33.847699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.158741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:33.848226Z","caller":"traceutil/trace.go:171","msg":"trace[891961356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"259.71877ms","start":"2026-02-03T10:27:33.588483Z","end":"2026-02-03T10:27:33.848202Z","steps":["trace[891961356] 'range keys from in-memory index tree' (duration: 259.095399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:45.616951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.704473ms","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-02-03T10:27:45.617035Z","caller":"traceutil/trace.go:171","msg":"trace[1249308649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:894; }","duration":"109.835106ms","start":"2026-02-03T10:27:45.507184Z","end":"2026-02-03T10:27:45.617019Z","steps":["trace[1249308649] 'range keys from in-memory index tree' (duration: 109.4807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:45.617296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.210153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T10:27:45.617329Z","caller":"traceutil/trace.go:171","msg":"trace[1241254184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"109.251525ms","start":"2026-02-03T10:27:45.508066Z","end":"2026-02-03T10:27:45.617318Z","steps":["trace[1241254184] 'range keys from in-memory index tree' (duration: 108.725755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:45.617461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.194393ms","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-02-03T10:27:45.617525Z","caller":"traceutil/trace.go:171","msg":"trace[933103976] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:894; }","duration":"110.298594ms","start":"2026-02-03T10:27:45.507215Z","end":"2026-02-03T10:27:45.617514Z","steps":["trace[933103976] 'range keys from in-memory index tree' (duration: 109.457879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:56.261434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.73442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:27:56.261488Z","caller":"traceutil/trace.go:171","msg":"trace[1954550466] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"249.579909ms","start":"2026-02-03T10:27:56.0119Z","end":"2026-02-03T10:27:56.26148Z","steps":["trace[1954550466] 'read index received' (duration: 50.441µs)","trace[1954550466] 'applied index is now lower than readState.Index' (duration: 249.528888ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:56.261538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.64042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:27:56.26155Z","caller":"traceutil/trace.go:171","msg":"trace[92456354] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:925; }","duration":"249.69105ms","start":"2026-02-03T10:27:56.011854Z","end":"2026-02-03T10:27:56.261546Z","steps":["trace[92456354] 'agreement among raft nodes before linearized reading' (duration: 249.649039ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:56.261588Z","caller":"traceutil/trace.go:171","msg":"trace[1157981868] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"547.029346ms","start":"2026-02-03T10:27:55.71454Z","end":"2026-02-03T10:27:56.26157Z","steps":["trace[1157981868] 'process raft request' (duration: 127.112353ms)","trace[1157981868] 'compare' (duration: 419.535116ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:56.261672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:55.714534Z","time spent":"547.093167ms","remote":"127.0.0.1:60564","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-02-03T10:27:56.754035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.782736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:56.754088Z","caller":"traceutil/trace.go:171","msg":"trace[864977344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"166.859137ms","start":"2026-02-03T10:27:56.587213Z","end":"2026-02-03T10:27:56.754073Z","steps":["trace[864977344] 'range keys from in-memory index tree' (duration: 166.749656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:56.754235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.792463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T10:27:56.75428Z","caller":"traceutil/trace.go:171","msg":"trace[535631244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:925; }","duration":"140.860734ms","start":"2026-02-03T10:27:56.613407Z","end":"2026-02-03T10:27:56.754268Z","steps":["trace[535631244] 'range keys from in-memory index tree' (duration: 140.672921ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:56.928417Z","caller":"traceutil/trace.go:171","msg":"trace[1982270796] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"171.09072ms","start":"2026-02-03T10:27:56.75731Z","end":"2026-02-03T10:27:56.9284Z","steps":["trace[1982270796] 'process raft request' (duration: 170.993377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:04.782566Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644190682421542,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T10:28:04.988714Z","caller":"traceutil/trace.go:171","msg":"trace[35772831] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"706.557564ms","start":"2026-02-03T10:28:04.282134Z","end":"2026-02-03T10:28:04.988692Z","steps":["trace[35772831] 'read index received' (duration: 706.378071ms)","trace[35772831] 'applied index is now lower than readState.Index' (duration: 178.593µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:04.988996Z","caller":"traceutil/trace.go:171","msg":"trace[1927309023] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"934.229319ms","start":"2026-02-03T10:28:04.054754Z","end":"2026-02-03T10:28:04.988983Z","steps":["trace[1927309023] 'process raft request' (duration: 933.82234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:04.989094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:04.054738Z","time spent":"934.288039ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:04.989351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.212547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T10:28:04.989384Z","caller":"traceutil/trace.go:171","msg":"trace[1941257940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:947; }","duration":"707.264808ms","start":"2026-02-03T10:28:04.282109Z","end":"2026-02-03T10:28:04.989374Z","steps":["trace[1941257940] 'agreement among raft nodes before linearized reading' (duration: 707.159276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:04.989413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:04.282097Z","time spent":"707.310389ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:28:04.989555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.579058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:04.989581Z","caller":"traceutil/trace.go:171","msg":"trace[1376131941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"398.626248ms","start":"2026-02-03T10:28:04.590947Z","end":"2026-02-03T10:28:04.989573Z","steps":["trace[1376131941] 'agreement among raft nodes before linearized reading' (duration: 398.586568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:04.989606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:04.590934Z","time spent":"398.66704ms","remote":"127.0.0.1:60298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T10:28:05.57063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.539645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:28:05.570755Z","caller":"traceutil/trace.go:171","msg":"trace[49237612] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"535.302683ms","start":"2026-02-03T10:28:05.035443Z","end":"2026-02-03T10:28:05.570745Z","steps":["trace[49237612] 'process raft request' (duration: 535.255072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:05.570797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:05.035427Z","time spent":"535.345944ms","remote":"127.0.0.1:60564","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-02-03T10:28:05.570949Z","caller":"traceutil/trace.go:171","msg":"trace[20146373] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"573.0647ms","start":"2026-02-03T10:28:04.997878Z","end":"2026-02-03T10:28:05.570943Z","steps":["trace[20146373] 'process raft request' (duration: 221.158809ms)","trace[20146373] 'compare' (duration: 351.389552ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:05.570974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:04.997862Z","time spent":"573.099001ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:05.571031Z","caller":"traceutil/trace.go:171","msg":"trace[745967322] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"572.924108ms","start":"2026-02-03T10:28:04.998101Z","end":"2026-02-03T10:28:05.571025Z","steps":["trace[745967322] 'read index received' (duration: 220.944415ms)","trace[745967322] 'applied index is now lower than readState.Index' (duration: 351.979163ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:05.571113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.015889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:05.571126Z","caller":"traceutil/trace.go:171","msg":"trace[281397578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"573.04414ms","start":"2026-02-03T10:28:04.998077Z","end":"2026-02-03T10:28:05.571121Z","steps":["trace[281397578] 'agreement among raft nodes before linearized reading' (duration: 573.021229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:05.571139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:04.99807Z","time spent":"573.06696ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T10:28:07.966468Z","caller":"traceutil/trace.go:171","msg":"trace[442714035] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"390.038904ms","start":"2026-02-03T10:28:07.576414Z","end":"2026-02-03T10:28:07.966453Z","steps":["trace[442714035] 'process raft request' (duration: 389.946003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:07.966571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:07.576405Z","time spent":"390.111057ms","remote":"127.0.0.1:60564","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-02-03T10:28:08.752441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.113331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:28:08.752593Z","caller":"traceutil/trace.go:171","msg":"trace[1578600174] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"1.174836467s","start":"2026-02-03T10:28:07.577744Z","end":"2026-02-03T10:28:08.75258Z","steps":["trace[1578600174] 'process raft request' (duration: 809.529401ms)","trace[1578600174] 'compare' (duration: 364.986829ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:08.752629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:07.577738Z","time spent":"1.174874737s","remote":"127.0.0.1:60564","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-02-03T10:28:09.170413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.911735ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T10:28:09.170478Z","caller":"traceutil/trace.go:171","msg":"trace[972281033] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"1.541027459s","start":"2026-02-03T10:28:07.62944Z","end":"2026-02-03T10:28:09.170467Z","steps":["trace[972281033] 'read index received' (duration: 337.409787ms)","trace[972281033] 'applied index is now lower than readState.Index' (duration: 1.203616962s)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:09.170739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541292984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T10:28:09.17076Z","caller":"traceutil/trace.go:171","msg":"trace[1889105222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"1.541345394s","start":"2026-02-03T10:28:07.629409Z","end":"2026-02-03T10:28:09.170755Z","steps":["trace[1889105222] 'agreement among raft nodes before linearized reading' (duration: 1.541267703s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:09.170777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:07.629395Z","time spent":"1.541377816s","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T10:28:09.208099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.645302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:09.20815Z","caller":"traceutil/trace.go:171","msg":"trace[859186168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"623.729913ms","start":"2026-02-03T10:28:08.584409Z","end":"2026-02-03T10:28:09.208139Z","steps":["trace[859186168] 'agreement among raft nodes before linearized reading' (duration: 623.660741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:09.208189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:08.584396Z","time spent":"623.781594ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T10:28:09.208533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.60213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:09.20856Z","caller":"traceutil/trace.go:171","msg":"trace[108074569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"790.65048ms","start":"2026-02-03T10:28:08.417904Z","end":"2026-02-03T10:28:09.208554Z","steps":["trace[108074569] 'agreement among raft nodes before linearized reading' (duration: 790.61507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:09.208581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:08.417892Z","time spent":"790.685252ms","remote":"127.0.0.1:60298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T10:28:09.208784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371707293s","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-02-03T10:28:09.208808Z","caller":"traceutil/trace.go:171","msg":"trace[2123484497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:955; }","duration":"1.371745294s","start":"2026-02-03T10:28:07.837058Z","end":"2026-02-03T10:28:09.208803Z","steps":["trace[2123484497] 'agreement among raft nodes before linearized reading' (duration: 1.371667552s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:09.208829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:07.83705Z","time spent":"1.371774794s","remote":"127.0.0.1:60564","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-02-03T10:28:09.208987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379828949s","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-02-03T10:28:09.209013Z","caller":"traceutil/trace.go:171","msg":"trace[69770942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"1.379877039s","start":"2026-02-03T10:28:07.829129Z","end":"2026-02-03T10:28:09.209006Z","steps":["trace[69770942] 'agreement among raft nodes before linearized reading' (duration: 1.378899661s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:09.209034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:07.829116Z","time spent":"1.37991367s","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-03T10:28:09.377827Z","caller":"traceutil/trace.go:171","msg":"trace[940814827] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"204.824549ms","start":"2026-02-03T10:28:09.172984Z","end":"2026-02-03T10:28:09.377809Z","steps":["trace[940814827] 'process raft request' (duration: 176.580983ms)","trace[940814827] 'compare' (duration: 28.086523ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:09.399354Z","caller":"traceutil/trace.go:171","msg":"trace[1512228761] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"180.565477ms","start":"2026-02-03T10:28:09.218773Z","end":"2026-02-03T10:28:09.399339Z","steps":["trace[1512228761] 'process raft request' (duration: 180.541467ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:09.399578Z","caller":"traceutil/trace.go:171","msg":"trace[1736929603] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"183.929513ms","start":"2026-02-03T10:28:09.215643Z","end":"2026-02-03T10:28:09.399572Z","steps":["trace[1736929603] 'process raft request' (duration: 183.616456ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:10.33526Z","caller":"traceutil/trace.go:171","msg":"trace[1425875881] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"111.780602ms","start":"2026-02-03T10:28:10.223456Z","end":"2026-02-03T10:28:10.335237Z","steps":["trace[1425875881] 'read index received' (duration: 111.591499ms)","trace[1425875881] 'applied index is now lower than readState.Index' (duration: 187.833µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:10.335397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.911154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:28:10.335443Z","caller":"traceutil/trace.go:171","msg":"trace[210621955] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:959; }","duration":"111.967235ms","start":"2026-02-03T10:28:10.223452Z","end":"2026-02-03T10:28:10.335419Z","steps":["trace[210621955] 'agreement among raft nodes before linearized reading' (duration: 111.870114ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:10.335749Z","caller":"traceutil/trace.go:171","msg":"trace[987338330] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"362.89904ms","start":"2026-02-03T10:28:09.972839Z","end":"2026-02-03T10:28:10.335738Z","steps":["trace[987338330] 'process raft request' (duration: 362.269909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:10.335825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:09.972831Z","time spent":"362.943211ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:11.534488Z","caller":"traceutil/trace.go:171","msg":"trace[53292108] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"107.978319ms","start":"2026-02-03T10:28:11.426479Z","end":"2026-02-03T10:28:11.534458Z","steps":["trace[53292108] 'process raft request' (duration: 84.883811ms)","trace[53292108] 'compare' (duration: 22.956156ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:14.49888Z","caller":"traceutil/trace.go:171","msg":"trace[830635178] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"147.83607ms","start":"2026-02-03T10:28:14.351021Z","end":"2026-02-03T10:28:14.498858Z","steps":["trace[830635178] 'process raft request' (duration: 147.676027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:14.864904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.883365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:14.865414Z","caller":"traceutil/trace.go:171","msg":"trace[1008605030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"279.413484ms","start":"2026-02-03T10:28:14.585965Z","end":"2026-02-03T10:28:14.865379Z","steps":["trace[1008605030] 'range keys from in-memory index tree' (duration: 278.822554ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:15.272266Z","caller":"traceutil/trace.go:171","msg":"trace[427827463] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"400.85819ms","start":"2026-02-03T10:28:14.871381Z","end":"2026-02-03T10:28:15.27224Z","steps":["trace[427827463] 'process raft request' (duration: 400.722538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:15.272439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:14.871361Z","time spent":"400.977782ms","remote":"127.0.0.1:60564","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-02-03T10:28:15.771777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.815743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:28:15.771853Z","caller":"traceutil/trace.go:171","msg":"trace[1506582792] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"335.932704ms","start":"2026-02-03T10:28:15.43591Z","end":"2026-02-03T10:28:15.771842Z","steps":["trace[1506582792] 'read index received' (duration: 174.922247ms)","trace[1506582792] 'applied index is now lower than readState.Index' (duration: 161.008527ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:15.772103Z","caller":"traceutil/trace.go:171","msg":"trace[191993976] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"458.055882ms","start":"2026-02-03T10:28:15.314039Z","end":"2026-02-03T10:28:15.772095Z","steps":["trace[191993976] 'process raft request' (duration: 296.860042ms)","trace[191993976] 'compare' (duration: 160.593699ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:15.772101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.070412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-03T10:28:15.772142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:15.314016Z","time spent":"458.104613ms","remote":"127.0.0.1:60564","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-02-03T10:28:15.772149Z","caller":"traceutil/trace.go:171","msg":"trace[1696460309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:980; }","duration":"268.154734ms","start":"2026-02-03T10:28:15.503981Z","end":"2026-02-03T10:28:15.772135Z","steps":["trace[1696460309] 'agreement among raft nodes before linearized reading' (duration: 267.988001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:15.77228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.370021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T10:28:15.772298Z","caller":"traceutil/trace.go:171","msg":"trace[1472859784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:980; }","duration":"336.420003ms","start":"2026-02-03T10:28:15.435872Z","end":"2026-02-03T10:28:15.772292Z","steps":["trace[1472859784] 'agreement among raft nodes before linearized reading' (duration: 336.365072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:15.772315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:15.435853Z","time spent":"336.457813ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T10:28:15.772465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.656931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:15.772482Z","caller":"traceutil/trace.go:171","msg":"trace[1070754798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"183.698041ms","start":"2026-02-03T10:28:15.588779Z","end":"2026-02-03T10:28:15.772477Z","steps":["trace[1070754798] 'agreement among raft nodes before linearized reading' (duration: 183.671771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:15.772592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.616424ms","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-02-03T10:28:15.772608Z","caller":"traceutil/trace.go:171","msg":"trace[441559128] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:980; }","duration":"227.668835ms","start":"2026-02-03T10:28:15.544934Z","end":"2026-02-03T10:28:15.772603Z","steps":["trace[441559128] 'agreement among raft nodes before linearized reading' (duration: 227.619834ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:15.934837Z","caller":"traceutil/trace.go:171","msg":"trace[1606152936] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"154.025135ms","start":"2026-02-03T10:28:15.780789Z","end":"2026-02-03T10:28:15.934814Z","steps":["trace[1606152936] 'process raft request' (duration: 154.002695ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:15.934958Z","caller":"traceutil/trace.go:171","msg":"trace[1870685441] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"154.684938ms","start":"2026-02-03T10:28:15.780263Z","end":"2026-02-03T10:28:15.934948Z","steps":["trace[1870685441] 'process raft request' (duration: 154.441694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:16.687574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.662995ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:16.687679Z","caller":"traceutil/trace.go:171","msg":"trace[255103235] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:983; }","duration":"480.771917ms","start":"2026-02-03T10:28:16.20689Z","end":"2026-02-03T10:28:16.687662Z","steps":["trace[255103235] 'range keys from in-memory index tree' (duration: 480.654405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:16.688116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.712841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T10:28:16.688157Z","caller":"traceutil/trace.go:171","msg":"trace[1511086349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:983; }","duration":"184.788112ms","start":"2026-02-03T10:28:16.50336Z","end":"2026-02-03T10:28:16.688148Z","steps":["trace[1511086349] 'range keys from in-memory index tree' (duration: 184.602179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:16.688457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.538481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:16.688485Z","caller":"traceutil/trace.go:171","msg":"trace[5280748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"102.566141ms","start":"2026-02-03T10:28:16.585911Z","end":"2026-02-03T10:28:16.688477Z","steps":["trace[5280748] 'range keys from in-memory index tree' (duration: 102.49872ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:23.109491Z","caller":"traceutil/trace.go:171","msg":"trace[26191783] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"332.389787ms","start":"2026-02-03T10:28:22.777078Z","end":"2026-02-03T10:28:23.109468Z","steps":["trace[26191783] 'process raft request' (duration: 331.372749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:23.109653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:22.77705Z","time spent":"332.53344ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:24.275758Z","caller":"traceutil/trace.go:171","msg":"trace[824344471] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"134.584242ms","start":"2026-02-03T10:28:24.141156Z","end":"2026-02-03T10:28:24.275741Z","steps":["trace[824344471] 'process raft request' (duration: 34.6544ms)","trace[824344471] 'compare' (duration: 99.85656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:26.587125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.101358ms","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-02-03T10:28:26.587201Z","caller":"traceutil/trace.go:171","msg":"trace[1462747869] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1049; }","duration":"209.207359ms","start":"2026-02-03T10:28:26.377972Z","end":"2026-02-03T10:28:26.58718Z","steps":["trace[1462747869] 'range keys from in-memory index tree' (duration: 209.047856ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:16.455792Z","caller":"traceutil/trace.go:171","msg":"trace[1891107354] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"138.112387ms","start":"2026-02-03T10:29:16.317657Z","end":"2026-02-03T10:29:16.455769Z","steps":["trace[1891107354] 'process raft request' (duration: 137.622578ms)"],"step_count":1}