{"level":"warn","ts":"2026-07-15T11:18:06.146535Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T11:18:06.146616Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-07-15T11:18:06.146684Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T11:18:06.146696Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-07-15T11:18:06.146712Z","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-07-15T11:18:06.147516Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-07-15T11:18:06.147592Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-07-15T11:18:06.15329Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.515435ms"} {"level":"info","ts":"2026-07-15T11:18:06.163733Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-07-15T11:18:06.163907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T11:18:06.163937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-07-15T11:18:06.164219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T11:18:06.164241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-07-15T11:18:06.164308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-07-15T11:18:06.174218Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T11:18:06.175486Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T11:18:06.176424Z","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-07-15T11:18:06.178665Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T11:18:06.178821Z","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-07-15T11:18:06.178981Z","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-07-15T11:18:06.179005Z","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-07-15T11:18:06.179414Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T11:18:06.187249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-07-15T11:18:06.187429Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-07-15T11:18:06.187812Z","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-07-15T11:18:06.187982Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-07-15T11:18:06.188038Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-07-15T11:18:06.188526Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T11:18:06.188566Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T11:18:06.464614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T11:18:06.464697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T11:18:06.464731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-07-15T11:18:06.464758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-07-15T11:18:06.464773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-07-15T11:18:06.464791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-07-15T11:18:06.464808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-07-15T11:18:06.465847Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T11:18:06.466001Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T11:18:06.466048Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T11:18:06.466179Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T11:18:06.467474Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T11:18:06.467702Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T11:18:06.46784Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T11:18:06.468522Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T11:18:06.468553Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T11:18:06.469932Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-07-15T11:18:06.471716Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T11:18:10.77439Z","caller":"traceutil/trace.go:171","msg":"trace[1241698938] linearizableReadLoop","detail":"{readStateIndex:252; appliedIndex:251; }","duration":"300.374563ms","start":"2026-07-15T11:18:10.473988Z","end":"2026-07-15T11:18:10.774362Z","steps":["trace[1241698938] 'read index received' (duration: 300.193339ms)","trace[1241698938] 'applied index is now lower than readState.Index' (duration: 180.054µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T11:18:10.774615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.589999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T11:18:10.7747Z","caller":"traceutil/trace.go:171","msg":"trace[429776723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:246; }","duration":"300.735142ms","start":"2026-07-15T11:18:10.47395Z","end":"2026-07-15T11:18:10.774685Z","steps":["trace[429776723] 'agreement among raft nodes before linearized reading' (duration: 300.574348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:10.774748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:10.473932Z","time spent":"300.806145ms","remote":"127.0.0.1:59086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-15T11:18:10.774934Z","caller":"traceutil/trace.go:171","msg":"trace[886009737] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"331.589398ms","start":"2026-07-15T11:18:10.443323Z","end":"2026-07-15T11:18:10.774913Z","steps":["trace[886009737] 'process raft request' (duration: 330.87058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:10.775997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:10.443303Z","time spent":"331.713121ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T11:18:11.841293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.688095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T11:18:11.841519Z","caller":"traceutil/trace.go:171","msg":"trace[1986610295] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"1.06250807s","start":"2026-07-15T11:18:10.778994Z","end":"2026-07-15T11:18:11.841502Z","steps":["trace[1986610295] 'process raft request' (duration: 233.207669ms)","trace[1986610295] 'compare' (duration: 828.487331ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T11:18:11.841602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:10.778956Z","time spent":"1.062615942s","remote":"127.0.0.1:59086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T11:18:11.841972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062538571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-yalo2m\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T11:18:11.842137Z","caller":"traceutil/trace.go:171","msg":"trace[518586481] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-yalo2m; range_end:; response_count:0; response_revision:247; }","duration":"1.062723196s","start":"2026-07-15T11:18:10.779351Z","end":"2026-07-15T11:18:11.842075Z","steps":["trace[518586481] 'agreement among raft nodes before linearized reading' (duration: 1.062230574s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:11.842228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:10.779336Z","time spent":"1.062876279s","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-yalo2m\" "} {"level":"info","ts":"2026-07-15T11:18:11.842095Z","caller":"traceutil/trace.go:171","msg":"trace[786746820] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:252; }","duration":"1.06207343s","start":"2026-07-15T11:18:10.779387Z","end":"2026-07-15T11:18:11.84146Z","steps":["trace[786746820] 'read index received' (duration: 232.713787ms)","trace[786746820] 'applied index is now lower than readState.Index' (duration: 829.357743ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:18:11.856329Z","caller":"traceutil/trace.go:171","msg":"trace[48276513] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"785.013347ms","start":"2026-07-15T11:18:11.071285Z","end":"2026-07-15T11:18:11.856298Z","steps":["trace[48276513] 'process raft request' (duration: 784.795001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:11.856344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.317672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T11:18:11.856454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:11.071256Z","time spent":"785.14326ms","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T11:18:11.856464Z","caller":"traceutil/trace.go:171","msg":"trace[351937892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:248; }","duration":"278.495127ms","start":"2026-07-15T11:18:11.577949Z","end":"2026-07-15T11:18:11.856444Z","steps":["trace[351937892] 'agreement among raft nodes before linearized reading' (duration: 278.307953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:43.822019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.108833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T11:18:43.822146Z","caller":"traceutil/trace.go:171","msg":"trace[972900756] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"164.643769ms","start":"2026-07-15T11:18:43.657484Z","end":"2026-07-15T11:18:43.822128Z","steps":["trace[972900756] 'read index received' (duration: 61.161517ms)","trace[972900756] 'applied index is now lower than readState.Index' (duration: 103.481112ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T11:18:43.822287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.804824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T11:18:43.822215Z","caller":"traceutil/trace.go:171","msg":"trace[1458157407] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"220.344523ms","start":"2026-07-15T11:18:43.601833Z","end":"2026-07-15T11:18:43.822178Z","steps":["trace[1458157407] 'process raft request' (duration: 117.000384ms)","trace[1458157407] 'compare' (duration: 102.866698ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:18:43.822332Z","caller":"traceutil/trace.go:171","msg":"trace[606966301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"164.912026ms","start":"2026-07-15T11:18:43.657409Z","end":"2026-07-15T11:18:43.822321Z","steps":["trace[606966301] 'agreement among raft nodes before linearized reading' (duration: 164.768702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:44.287565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.682268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T11:18:44.288072Z","caller":"traceutil/trace.go:171","msg":"trace[2084784690] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"456.659889ms","start":"2026-07-15T11:18:43.831393Z","end":"2026-07-15T11:18:44.288053Z","steps":["trace[2084784690] 'process raft request' (duration: 129.410597ms)","trace[2084784690] 'compare' (duration: 326.512574ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:18:44.288262Z","caller":"traceutil/trace.go:171","msg":"trace[1731758887] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:517; }","duration":"255.235318ms","start":"2026-07-15T11:18:44.03298Z","end":"2026-07-15T11:18:44.288215Z","steps":["trace[1731758887] 'read index received' (duration: 201.508433ms)","trace[1731758887] 'applied index is now lower than readState.Index' (duration: 53.721545ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:18:44.288406Z","caller":"traceutil/trace.go:171","msg":"trace[651951159] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"456.351813ms","start":"2026-07-15T11:18:43.831913Z","end":"2026-07-15T11:18:44.288265Z","steps":["trace[651951159] 'process raft request' (duration: 456.008124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:44.288651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.294814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-15T11:18:44.288649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.609557ms","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-07-15T11:18:44.288941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:43.831361Z","time spent":"456.946877ms","remote":"127.0.0.1:59258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3827,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T11:18:44.288991Z","caller":"traceutil/trace.go:171","msg":"trace[1341413046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:501; }","duration":"122.699264ms","start":"2026-07-15T11:18:44.166276Z","end":"2026-07-15T11:18:44.288975Z","steps":["trace[1341413046] 'agreement among raft nodes before linearized reading' (duration: 122.151041ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:44.289045Z","caller":"traceutil/trace.go:171","msg":"trace[1156475290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:501; }","duration":"256.09338ms","start":"2026-07-15T11:18:44.032921Z","end":"2026-07-15T11:18:44.289014Z","steps":["trace[1156475290] 'agreement among raft nodes before linearized reading' (duration: 255.463324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:44.288832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.788802ms","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-07-15T11:18:44.29062Z","caller":"traceutil/trace.go:171","msg":"trace[775843621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:501; }","duration":"257.659318ms","start":"2026-07-15T11:18:44.032936Z","end":"2026-07-15T11:18:44.290595Z","steps":["trace[775843621] 'agreement among raft nodes before linearized reading' (duration: 255.481304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:44.28866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:43.831882Z","time spent":"456.656039ms","remote":"127.0.0.1:59290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T11:18:44.599198Z","caller":"traceutil/trace.go:171","msg":"trace[1653372759] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"305.53572ms","start":"2026-07-15T11:18:44.293633Z","end":"2026-07-15T11:18:44.599168Z","steps":["trace[1653372759] 'process raft request' (duration: 209.945721ms)","trace[1653372759] 'compare' (duration: 95.392354ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T11:18:44.599333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:44.293606Z","time spent":"305.663394ms","remote":"127.0.0.1:59286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T11:18:45.872608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049406778s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-07-15T11:18:45.873171Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.049992912s","expected-duration":"1s"} {"level":"info","ts":"2026-07-15T11:18:45.87335Z","caller":"traceutil/trace.go:171","msg":"trace[1869324313] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.579613877s","start":"2026-07-15T11:18:44.293703Z","end":"2026-07-15T11:18:45.873317Z","steps":["trace[1869324313] 'process raft request' (duration: 529.409489ms)","trace[1869324313] 'compare' (duration: 1.049279406s)"],"step_count":2} {"level":"warn","ts":"2026-07-15T11:18:45.873491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:44.293688Z","time spent":"1.57970558s","remote":"127.0.0.1:59086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T11:18:45.87374Z","caller":"traceutil/trace.go:171","msg":"trace[477772998] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.579583937s","start":"2026-07-15T11:18:44.294122Z","end":"2026-07-15T11:18:45.873706Z","steps":["trace[477772998] 'process raft request' (duration: 1.57888795s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:45.873919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:44.294009Z","time spent":"1.579812322s","remote":"127.0.0.1:59086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T11:18:45.873895Z","caller":"traceutil/trace.go:171","msg":"trace[1818115764] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.576784408s","start":"2026-07-15T11:18:44.297082Z","end":"2026-07-15T11:18:45.873866Z","steps":["trace[1818115764] 'process raft request' (duration: 1.576507761s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:45.874352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:44.297061Z","time spent":"1.577209528s","remote":"127.0.0.1:59086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T11:18:45.874492Z","caller":"traceutil/trace.go:171","msg":"trace[587860754] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.577033614s","start":"2026-07-15T11:18:44.297432Z","end":"2026-07-15T11:18:45.874466Z","steps":["trace[587860754] 'process raft request' (duration: 1.576485131s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:45.874601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:44.297423Z","time spent":"1.577120986s","remote":"127.0.0.1:59086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T11:18:45.876226Z","caller":"traceutil/trace.go:171","msg":"trace[205549027] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.515031087s","start":"2026-07-15T11:18:44.361169Z","end":"2026-07-15T11:18:45.8762Z","steps":["trace[205549027] 'process raft request' (duration: 1.513232303s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:45.876299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:44.361131Z","time spent":"1.515130639s","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T11:18:45.876333Z","caller":"traceutil/trace.go:171","msg":"trace[1862367611] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:519; }","duration":"1.577893345s","start":"2026-07-15T11:18:44.298423Z","end":"2026-07-15T11:18:45.876316Z","steps":["trace[1862367611] 'read index received' (duration: 205.251056ms)","trace[1862367611] 'applied index is now lower than readState.Index' (duration: 1.372639829s)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:18:45.876249Z","caller":"traceutil/trace.go:171","msg":"trace[375438929] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.269071854s","start":"2026-07-15T11:18:44.60716Z","end":"2026-07-15T11:18:45.876232Z","steps":["trace[375438929] 'process raft request' (duration: 1.269011143s)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:45.876506Z","caller":"traceutil/trace.go:171","msg":"trace[2112134586] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.271739299s","start":"2026-07-15T11:18:44.604739Z","end":"2026-07-15T11:18:45.876478Z","steps":["trace[2112134586] 'process raft request' (duration: 1.271386451s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:45.87666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:44.604712Z","time spent":"1.271910154s","remote":"127.0.0.1:58894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T11:18:45.876577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578144691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T11:18:45.876837Z","caller":"traceutil/trace.go:171","msg":"trace[1402760320] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:509; }","duration":"1.578429118s","start":"2026-07-15T11:18:44.298393Z","end":"2026-07-15T11:18:45.876822Z","steps":["trace[1402760320] 'agreement among raft nodes before linearized reading' (duration: 1.578027438s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:45.876877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:44.298386Z","time spent":"1.578481029s","remote":"127.0.0.1:58948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-07-15T11:18:45.877636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:44.60715Z","time spent":"1.269285479s","remote":"127.0.0.1:59258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4320,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T11:18:45.885603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225970539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T11:18:45.885693Z","caller":"traceutil/trace.go:171","msg":"trace[110033912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"1.226114583s","start":"2026-07-15T11:18:44.659559Z","end":"2026-07-15T11:18:45.885673Z","steps":["trace[110033912] 'agreement among raft nodes before linearized reading' (duration: 1.22596773s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:45.885739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:44.659467Z","time spent":"1.226264447s","remote":"127.0.0.1:58810","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-07-15T11:18:48.117225Z","caller":"traceutil/trace.go:171","msg":"trace[1102105675] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"427.555708ms","start":"2026-07-15T11:18:47.689623Z","end":"2026-07-15T11:18:48.117179Z","steps":["trace[1102105675] 'process raft request' (duration: 414.387346ms)","trace[1102105675] 'compare' (duration: 12.878095ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T11:18:48.117452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:47.689596Z","time spent":"427.730973ms","remote":"127.0.0.1:58864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T11:18:48.117869Z","caller":"traceutil/trace.go:171","msg":"trace[1855054832] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"382.225169ms","start":"2026-07-15T11:18:47.735626Z","end":"2026-07-15T11:18:48.117851Z","steps":["trace[1855054832] 'process raft request' (duration: 381.665365ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:48.117876Z","caller":"traceutil/trace.go:171","msg":"trace[1337172375] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"418.24315ms","start":"2026-07-15T11:18:47.6996Z","end":"2026-07-15T11:18:48.117843Z","steps":["trace[1337172375] 'process raft request' (duration: 417.510272ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:48.117871Z","caller":"traceutil/trace.go:171","msg":"trace[2008060194] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:578; }","duration":"239.827072ms","start":"2026-07-15T11:18:47.878016Z","end":"2026-07-15T11:18:48.117843Z","steps":["trace[2008060194] 'read index received' (duration: 225.989214ms)","trace[2008060194] 'applied index is now lower than readState.Index' (duration: 13.836678ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T11:18:48.117991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:47.735587Z","time spent":"382.361942ms","remote":"127.0.0.1:58864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T11:18:48.118002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:47.699561Z","time spent":"418.393754ms","remote":"127.0.0.1:58864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T11:18:48.118048Z","caller":"traceutil/trace.go:171","msg":"trace[671817928] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"315.30597ms","start":"2026-07-15T11:18:47.802728Z","end":"2026-07-15T11:18:48.118034Z","steps":["trace[671817928] 'process raft request' (duration: 314.90788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:48.118171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.565766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-15T11:18:48.118213Z","caller":"traceutil/trace.go:171","msg":"trace[650581196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"239.637688ms","start":"2026-07-15T11:18:47.878562Z","end":"2026-07-15T11:18:48.1182Z","steps":["trace[650581196] 'agreement among raft nodes before linearized reading' (duration: 239.529065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:48.118213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:47.802682Z","time spent":"315.447803ms","remote":"127.0.0.1:58864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T11:18:48.118259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.160842ms","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-07-15T11:18:48.11829Z","caller":"traceutil/trace.go:171","msg":"trace[1443815188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"238.256514ms","start":"2026-07-15T11:18:47.880025Z","end":"2026-07-15T11:18:48.118282Z","steps":["trace[1443815188] 'agreement among raft nodes before linearized reading' (duration: 238.190612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:48.118436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.454564ms","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-07-15T11:18:48.118453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.435167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-15T11:18:48.118478Z","caller":"traceutil/trace.go:171","msg":"trace[1855266864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"239.549626ms","start":"2026-07-15T11:18:47.878916Z","end":"2026-07-15T11:18:48.118466Z","steps":["trace[1855266864] 'agreement among raft nodes before linearized reading' (duration: 239.472424ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:48.118482Z","caller":"traceutil/trace.go:171","msg":"trace[1595457982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"240.501749ms","start":"2026-07-15T11:18:47.877972Z","end":"2026-07-15T11:18:48.118474Z","steps":["trace[1595457982] 'agreement among raft nodes before linearized reading' (duration: 240.378676ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:48.529553Z","caller":"traceutil/trace.go:171","msg":"trace[764449156] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"107.03088ms","start":"2026-07-15T11:18:48.422475Z","end":"2026-07-15T11:18:48.529506Z","steps":["trace[764449156] 'process raft request' (duration: 69.833089ms)","trace[764449156] 'compare' (duration: 37.010016ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:18:49.013614Z","caller":"traceutil/trace.go:171","msg":"trace[74944891] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"102.52921ms","start":"2026-07-15T11:18:48.911001Z","end":"2026-07-15T11:18:49.01353Z","steps":["trace[74944891] 'process raft request' (duration: 102.193442ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:49.309608Z","caller":"traceutil/trace.go:171","msg":"trace[287443896] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"154.859731ms","start":"2026-07-15T11:18:49.154715Z","end":"2026-07-15T11:18:49.309575Z","steps":["trace[287443896] 'process raft request' (duration: 154.702207ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:49.321638Z","caller":"traceutil/trace.go:171","msg":"trace[1446089873] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"104.776295ms","start":"2026-07-15T11:18:49.216845Z","end":"2026-07-15T11:18:49.321621Z","steps":["trace[1446089873] 'process raft request' (duration: 103.877153ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:49.322009Z","caller":"traceutil/trace.go:171","msg":"trace[1393573084] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"103.969986ms","start":"2026-07-15T11:18:49.21803Z","end":"2026-07-15T11:18:49.322Z","steps":["trace[1393573084] 'process raft request' (duration: 103.538325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:54.351603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.172094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-15T11:18:54.351731Z","caller":"traceutil/trace.go:171","msg":"trace[413181794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"183.356739ms","start":"2026-07-15T11:18:54.168346Z","end":"2026-07-15T11:18:54.351702Z","steps":["trace[413181794] 'range keys from in-memory index tree' (duration: 182.906068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:54.351764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.238167ms","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-07-15T11:18:54.35186Z","caller":"traceutil/trace.go:171","msg":"trace[971847801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"181.3749ms","start":"2026-07-15T11:18:54.170463Z","end":"2026-07-15T11:18:54.351838Z","steps":["trace[971847801] 'range keys from in-memory index tree' (duration: 181.023591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:54.352064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.125349ms","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-07-15T11:18:54.352093Z","caller":"traceutil/trace.go:171","msg":"trace[1050879958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"180.16316ms","start":"2026-07-15T11:18:54.171919Z","end":"2026-07-15T11:18:54.352082Z","steps":["trace[1050879958] 'range keys from in-memory index tree' (duration: 179.994546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:54.352241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.48543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-15T11:18:54.352284Z","caller":"traceutil/trace.go:171","msg":"trace[1606177963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"185.564312ms","start":"2026-07-15T11:18:54.166707Z","end":"2026-07-15T11:18:54.352272Z","steps":["trace[1606177963] 'range keys from in-memory index tree' (duration: 185.291726ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:54.669199Z","caller":"traceutil/trace.go:171","msg":"trace[1998308446] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"255.74736ms","start":"2026-07-15T11:18:54.413399Z","end":"2026-07-15T11:18:54.669146Z","steps":["trace[1998308446] 'process raft request' (duration: 254.855388ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:55.128732Z","caller":"traceutil/trace.go:171","msg":"trace[1669953150] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"399.100532ms","start":"2026-07-15T11:18:54.729596Z","end":"2026-07-15T11:18:55.128696Z","steps":["trace[1669953150] 'process raft request' (duration: 334.847359ms)","trace[1669953150] 'compare' (duration: 64.025407ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T11:18:55.128854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:54.729564Z","time spent":"399.232726ms","remote":"127.0.0.1:59318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T11:18:55.215318Z","caller":"traceutil/trace.go:171","msg":"trace[1719040852] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:661; }","duration":"179.080485ms","start":"2026-07-15T11:18:55.036213Z","end":"2026-07-15T11:18:55.215294Z","steps":["trace[1719040852] 'read index received' (duration: 28.232362ms)","trace[1719040852] 'applied index is now lower than readState.Index' (duration: 150.846573ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T11:18:55.215589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.356361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ckffj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-15T11:18:55.215688Z","caller":"traceutil/trace.go:171","msg":"trace[900157131] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ckffj; range_end:; response_count:1; response_revision:639; }","duration":"179.500125ms","start":"2026-07-15T11:18:55.03616Z","end":"2026-07-15T11:18:55.21566Z","steps":["trace[900157131] 'agreement among raft nodes before linearized reading' (duration: 179.217598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:56.866252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305497407393257797,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T11:18:57.125178Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.891342268s","expected-duration":"1s"} {"level":"info","ts":"2026-07-15T11:18:57.125931Z","caller":"traceutil/trace.go:171","msg":"trace[141292919] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.8922528s","start":"2026-07-15T11:18:55.233645Z","end":"2026-07-15T11:18:57.125897Z","steps":["trace[141292919] 'process raft request' (duration: 1.891912581s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:57.126136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:55.233606Z","time spent":"1.892429584s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T11:18:57.222061Z","caller":"traceutil/trace.go:171","msg":"trace[659494698] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"856.086039ms","start":"2026-07-15T11:18:56.365932Z","end":"2026-07-15T11:18:57.222018Z","steps":["trace[659494698] 'read index received' (duration: 759.757762ms)","trace[659494698] 'applied index is now lower than readState.Index' (duration: 96.326747ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:18:57.222098Z","caller":"traceutil/trace.go:171","msg":"trace[328705757] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.6084948s","start":"2026-07-15T11:18:55.613559Z","end":"2026-07-15T11:18:57.222053Z","steps":["trace[328705757] 'process raft request' (duration: 1.604066952s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:57.222352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:55.613439Z","time spent":"1.608814648s","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T11:18:57.222397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.420407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-15T11:18:57.222613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.220472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-15T11:18:57.222658Z","caller":"traceutil/trace.go:171","msg":"trace[637151386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"817.273423ms","start":"2026-07-15T11:18:56.40537Z","end":"2026-07-15T11:18:57.222643Z","steps":["trace[637151386] 'agreement among raft nodes before linearized reading' (duration: 817.188771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:57.222707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:56.40536Z","time spent":"817.337564ms","remote":"127.0.0.1:59086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-15T11:18:57.222726Z","caller":"traceutil/trace.go:171","msg":"trace[638308451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"856.710793ms","start":"2026-07-15T11:18:56.365862Z","end":"2026-07-15T11:18:57.222573Z","steps":["trace[638308451] 'agreement among raft nodes before linearized reading' (duration: 856.323164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:57.222907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:56.365833Z","time spent":"857.055242ms","remote":"127.0.0.1:59086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T11:18:57.222474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.863158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-15T11:18:57.222906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.291618ms","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-07-15T11:18:57.223152Z","caller":"traceutil/trace.go:171","msg":"trace[1834054352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"818.549714ms","start":"2026-07-15T11:18:56.404585Z","end":"2026-07-15T11:18:57.223135Z","steps":["trace[1834054352] 'agreement among raft nodes before linearized reading' (duration: 818.160635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:57.222542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.188283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T11:18:57.223298Z","caller":"traceutil/trace.go:171","msg":"trace[1179749402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"563.972032ms","start":"2026-07-15T11:18:56.659313Z","end":"2026-07-15T11:18:57.223285Z","steps":["trace[1179749402] 'agreement among raft nodes before linearized reading' (duration: 563.204123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:57.22333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:56.404303Z","time spent":"818.960404ms","remote":"127.0.0.1:59086","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-07-15T11:18:57.223272Z","caller":"traceutil/trace.go:171","msg":"trace[693638276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"818.644066ms","start":"2026-07-15T11:18:56.404494Z","end":"2026-07-15T11:18:57.223138Z","steps":["trace[693638276] 'agreement among raft nodes before linearized reading' (duration: 817.783025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:57.223365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:56.659291Z","time spent":"564.061404ms","remote":"127.0.0.1:58810","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-07-15T11:18:57.223604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:56.404486Z","time spent":"819.064417ms","remote":"127.0.0.1:59086","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-07-15T11:18:57.574737Z","caller":"traceutil/trace.go:171","msg":"trace[1256404205] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"194.652041ms","start":"2026-07-15T11:18:57.380034Z","end":"2026-07-15T11:18:57.574686Z","steps":["trace[1256404205] 'process raft request' (duration: 194.371774ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:57.574785Z","caller":"traceutil/trace.go:171","msg":"trace[1004285239] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"195.112773ms","start":"2026-07-15T11:18:57.379596Z","end":"2026-07-15T11:18:57.574709Z","steps":["trace[1004285239] 'process raft request' (duration: 194.58348ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:58.640893Z","caller":"traceutil/trace.go:171","msg":"trace[1345627027] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"255.518705ms","start":"2026-07-15T11:18:58.385345Z","end":"2026-07-15T11:18:58.640864Z","steps":["trace[1345627027] 'process raft request' (duration: 255.33412ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:59.202822Z","caller":"traceutil/trace.go:171","msg":"trace[827695594] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"134.547358ms","start":"2026-07-15T11:18:59.06825Z","end":"2026-07-15T11:18:59.202797Z","steps":["trace[827695594] 'process raft request' (duration: 134.408085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:18:59.863128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.3724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T11:18:59.863286Z","caller":"traceutil/trace.go:171","msg":"trace[88609479] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"204.073821ms","start":"2026-07-15T11:18:59.659187Z","end":"2026-07-15T11:18:59.863261Z","steps":["trace[88609479] 'read index received' (duration: 46.891µs)","trace[88609479] 'applied index is now lower than readState.Index' (duration: 204.02475ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:18:59.863299Z","caller":"traceutil/trace.go:171","msg":"trace[955134762] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"400.285761ms","start":"2026-07-15T11:18:59.462989Z","end":"2026-07-15T11:18:59.863275Z","steps":["trace[955134762] 'process raft request' (duration: 135.513981ms)","trace[955134762] 'compare' (duration: 263.869468ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T11:18:59.863388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:18:59.462966Z","time spent":"400.385664ms","remote":"127.0.0.1:58894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T11:18:59.863494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.290816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T11:18:59.863572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.422968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T11:18:59.863572Z","caller":"traceutil/trace.go:171","msg":"trace[1545007022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"204.405349ms","start":"2026-07-15T11:18:59.659144Z","end":"2026-07-15T11:18:59.86355Z","steps":["trace[1545007022] 'agreement among raft nodes before linearized reading' (duration: 204.222875ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:18:59.863616Z","caller":"traceutil/trace.go:171","msg":"trace[1259866958] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:664; }","duration":"199.51233ms","start":"2026-07-15T11:18:59.66409Z","end":"2026-07-15T11:18:59.863602Z","steps":["trace[1259866958] 'agreement among raft nodes before linearized reading' (duration: 199.290965ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:19:00.504546Z","caller":"traceutil/trace.go:171","msg":"trace[1554662358] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"163.017512ms","start":"2026-07-15T11:19:00.341495Z","end":"2026-07-15T11:19:00.504513Z","steps":["trace[1554662358] 'process raft request' (duration: 162.866149ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:19:00.54404Z","caller":"traceutil/trace.go:171","msg":"trace[1523824948] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"201.649234ms","start":"2026-07-15T11:19:00.342373Z","end":"2026-07-15T11:19:00.544022Z","steps":["trace[1523824948] 'read index received' (duration: 162.349516ms)","trace[1523824948] 'applied index is now lower than readState.Index' (duration: 39.299148ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:19:00.54404Z","caller":"traceutil/trace.go:171","msg":"trace[1931414715] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"201.780667ms","start":"2026-07-15T11:19:00.342222Z","end":"2026-07-15T11:19:00.544003Z","steps":["trace[1931414715] 'process raft request' (duration: 200.832394ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:19:00.544597Z","caller":"traceutil/trace.go:171","msg":"trace[47988644] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"201.51162ms","start":"2026-07-15T11:19:00.343077Z","end":"2026-07-15T11:19:00.544589Z","steps":["trace[47988644] 'process raft request' (duration: 200.812543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:19:00.544738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.29774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T11:19:00.544799Z","caller":"traceutil/trace.go:171","msg":"trace[212294245] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"202.448583ms","start":"2026-07-15T11:19:00.342331Z","end":"2026-07-15T11:19:00.54478Z","steps":["trace[212294245] 'agreement among raft nodes before linearized reading' (duration: 202.282139ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:19:00.544793Z","caller":"traceutil/trace.go:171","msg":"trace[216561216] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"201.853068ms","start":"2026-07-15T11:19:00.342872Z","end":"2026-07-15T11:19:00.544725Z","steps":["trace[216561216] 'process raft request' (duration: 200.979097ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:19:12.305376Z","caller":"traceutil/trace.go:171","msg":"trace[695486471] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"115.619437ms","start":"2026-07-15T11:19:12.189734Z","end":"2026-07-15T11:19:12.305354Z","steps":["trace[695486471] 'process raft request' (duration: 115.480524ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:19:16.830026Z","caller":"traceutil/trace.go:171","msg":"trace[636979285] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"117.282357ms","start":"2026-07-15T11:19:16.712715Z","end":"2026-07-15T11:19:16.829997Z","steps":["trace[636979285] 'process raft request' (duration: 115.76143ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:19:49.085127Z","caller":"traceutil/trace.go:171","msg":"trace[109971128] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"268.254065ms","start":"2026-07-15T11:19:48.816852Z","end":"2026-07-15T11:19:49.085106Z","steps":["trace[109971128] 'process raft request' (duration: 268.108801ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:13.448703Z","caller":"traceutil/trace.go:171","msg":"trace[1948075204] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"159.421591ms","start":"2026-07-15T11:20:13.289247Z","end":"2026-07-15T11:20:13.448668Z","steps":["trace[1948075204] 'process raft request' (duration: 159.243406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:13.779303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.043145ms","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-07-15T11:20:13.779441Z","caller":"traceutil/trace.go:171","msg":"trace[2025824234] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1101; }","duration":"183.204739ms","start":"2026-07-15T11:20:13.596217Z","end":"2026-07-15T11:20:13.779422Z","steps":["trace[2025824234] 'range keys from in-memory index tree' (duration: 183.020154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:13.779492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.420976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T11:20:13.779595Z","caller":"traceutil/trace.go:171","msg":"trace[1464646778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"119.58577ms","start":"2026-07-15T11:20:13.659982Z","end":"2026-07-15T11:20:13.779568Z","steps":["trace[1464646778] 'range keys from in-memory index tree' (duration: 119.287393ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:13.90905Z","caller":"traceutil/trace.go:171","msg":"trace[486507793] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"123.950187ms","start":"2026-07-15T11:20:13.785069Z","end":"2026-07-15T11:20:13.909019Z","steps":["trace[486507793] 'process raft request' (duration: 123.861525ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:13.909083Z","caller":"traceutil/trace.go:171","msg":"trace[905820786] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"124.438409ms","start":"2026-07-15T11:20:13.784606Z","end":"2026-07-15T11:20:13.909044Z","steps":["trace[905820786] 'process raft request' (duration: 105.59808ms)","trace[905820786] 'compare' (duration: 18.561272ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:20:13.90945Z","caller":"traceutil/trace.go:171","msg":"trace[683863278] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"123.054265ms","start":"2026-07-15T11:20:13.786347Z","end":"2026-07-15T11:20:13.909401Z","steps":["trace[683863278] 'process raft request' (duration: 122.629975ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:15.063375Z","caller":"traceutil/trace.go:171","msg":"trace[654757509] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"249.679196ms","start":"2026-07-15T11:20:14.813667Z","end":"2026-07-15T11:20:15.063346Z","steps":["trace[654757509] 'process raft request' (duration: 249.499882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:15.711337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.958517ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-15T11:20:15.711483Z","caller":"traceutil/trace.go:171","msg":"trace[1165931323] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1149; }","duration":"581.823731ms","start":"2026-07-15T11:20:15.129634Z","end":"2026-07-15T11:20:15.711458Z","steps":["trace[1165931323] 'read index received' (duration: 74.142µs)","trace[1165931323] 'applied index is now lower than readState.Index' (duration: 581.747709ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T11:20:15.711672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.030096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T11:20:15.711714Z","caller":"traceutil/trace.go:171","msg":"trace[540241737] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1107; }","duration":"582.133549ms","start":"2026-07-15T11:20:15.129566Z","end":"2026-07-15T11:20:15.7117Z","steps":["trace[540241737] 'agreement among raft nodes before linearized reading' (duration: 582.039396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:15.711759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:20:15.129526Z","time spent":"582.223181ms","remote":"127.0.0.1:59138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-07-15T11:20:15.711874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.476363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T11:20:15.71199Z","caller":"traceutil/trace.go:171","msg":"trace[2119609793] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1107; }","duration":"466.872673ms","start":"2026-07-15T11:20:15.245087Z","end":"2026-07-15T11:20:15.71196Z","steps":["trace[2119609793] 'agreement among raft nodes before linearized reading' (duration: 466.652448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:15.712144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.539688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-15T11:20:15.712213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:20:15.245046Z","time spent":"466.989776ms","remote":"127.0.0.1:58898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-07-15T11:20:15.712231Z","caller":"traceutil/trace.go:171","msg":"trace[493896357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1107; }","duration":"257.63963ms","start":"2026-07-15T11:20:15.454544Z","end":"2026-07-15T11:20:15.712183Z","steps":["trace[493896357] 'agreement among raft nodes before linearized reading' (duration: 257.416515ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:16.297182Z","caller":"traceutil/trace.go:171","msg":"trace[1995512896] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"135.937789ms","start":"2026-07-15T11:20:16.1612Z","end":"2026-07-15T11:20:16.297138Z","steps":["trace[1995512896] 'process raft request' (duration: 84.446625ms)","trace[1995512896] 'compare' (duration: 50.813877ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T11:20:18.233638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.086931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-15T11:20:18.233672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.965067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T11:20:18.233769Z","caller":"traceutil/trace.go:171","msg":"trace[2014014118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1114; }","duration":"304.304487ms","start":"2026-07-15T11:20:17.929439Z","end":"2026-07-15T11:20:18.233743Z","steps":["trace[2014014118] 'range keys from in-memory index tree' (duration: 303.741752ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:18.233782Z","caller":"traceutil/trace.go:171","msg":"trace[233438750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1114; }","duration":"230.230123ms","start":"2026-07-15T11:20:18.003527Z","end":"2026-07-15T11:20:18.233757Z","steps":["trace[233438750] 'range keys from in-memory index tree' (duration: 229.833384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:18.233832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:20:17.929408Z","time spent":"304.410169ms","remote":"127.0.0.1:59086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T11:20:18.233672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.877659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-15T11:20:18.233907Z","caller":"traceutil/trace.go:171","msg":"trace[67586427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1114; }","duration":"231.186356ms","start":"2026-07-15T11:20:18.002705Z","end":"2026-07-15T11:20:18.233891Z","steps":["trace[67586427] 'range keys from in-memory index tree' (duration: 230.559851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:18.234166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.947341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-15T11:20:18.234284Z","caller":"traceutil/trace.go:171","msg":"trace[333062397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1114; }","duration":"231.228538ms","start":"2026-07-15T11:20:18.003025Z","end":"2026-07-15T11:20:18.234254Z","steps":["trace[333062397] 'range keys from in-memory index tree' (duration: 230.057209ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:18.416247Z","caller":"traceutil/trace.go:171","msg":"trace[1070992421] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"177.010068ms","start":"2026-07-15T11:20:18.239205Z","end":"2026-07-15T11:20:18.416215Z","steps":["trace[1070992421] 'process raft request' (duration: 176.823424ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:18.431083Z","caller":"traceutil/trace.go:171","msg":"trace[759146933] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"190.593589ms","start":"2026-07-15T11:20:18.240436Z","end":"2026-07-15T11:20:18.43103Z","steps":["trace[759146933] 'process raft request' (duration: 190.409214ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:18.431415Z","caller":"traceutil/trace.go:171","msg":"trace[981700833] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"190.868215ms","start":"2026-07-15T11:20:18.240517Z","end":"2026-07-15T11:20:18.431385Z","steps":["trace[981700833] 'process raft request' (duration: 190.464976ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:18.43168Z","caller":"traceutil/trace.go:171","msg":"trace[1561452670] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"190.407624ms","start":"2026-07-15T11:20:18.241255Z","end":"2026-07-15T11:20:18.431662Z","steps":["trace[1561452670] 'process raft request' (duration: 190.089536ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:18.431698Z","caller":"traceutil/trace.go:171","msg":"trace[959738912] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"128.443466ms","start":"2026-07-15T11:20:18.303232Z","end":"2026-07-15T11:20:18.431675Z","steps":["trace[959738912] 'process raft request' (duration: 128.257072ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:30.666823Z","caller":"traceutil/trace.go:171","msg":"trace[1374521574] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1200; }","duration":"186.480479ms","start":"2026-07-15T11:20:30.480317Z","end":"2026-07-15T11:20:30.666797Z","steps":["trace[1374521574] 'read index received' (duration: 186.332465ms)","trace[1374521574] 'applied index is now lower than readState.Index' (duration: 147.264µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:20:30.666932Z","caller":"traceutil/trace.go:171","msg":"trace[2122355057] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"193.407867ms","start":"2026-07-15T11:20:30.473486Z","end":"2026-07-15T11:20:30.666894Z","steps":["trace[2122355057] 'process raft request' (duration: 193.142431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:30.667148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.101897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-15T11:20:30.667192Z","caller":"traceutil/trace.go:171","msg":"trace[409691269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1155; }","duration":"183.175648ms","start":"2026-07-15T11:20:30.484005Z","end":"2026-07-15T11:20:30.667181Z","steps":["trace[409691269] 'agreement among raft nodes before linearized reading' (duration: 183.006134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:30.667192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.992564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T11:20:30.667226Z","caller":"traceutil/trace.go:171","msg":"trace[115758980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1155; }","duration":"183.091416ms","start":"2026-07-15T11:20:30.484126Z","end":"2026-07-15T11:20:30.667218Z","steps":["trace[115758980] 'agreement among raft nodes before linearized reading' (duration: 182.891031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:30.667385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.067403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T11:20:30.667422Z","caller":"traceutil/trace.go:171","msg":"trace[917231973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1155; }","duration":"187.145255ms","start":"2026-07-15T11:20:30.480267Z","end":"2026-07-15T11:20:30.667412Z","steps":["trace[917231973] 'agreement among raft nodes before linearized reading' (duration: 186.674353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:30.667633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.345517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-15T11:20:30.667848Z","caller":"traceutil/trace.go:171","msg":"trace[798511798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1155; }","duration":"184.653284ms","start":"2026-07-15T11:20:30.483174Z","end":"2026-07-15T11:20:30.667828Z","steps":["trace[798511798] 'agreement among raft nodes before linearized reading' (duration: 183.819914ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:31.19181Z","caller":"traceutil/trace.go:171","msg":"trace[1609585585] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"160.524166ms","start":"2026-07-15T11:20:31.031233Z","end":"2026-07-15T11:20:31.191757Z","steps":["trace[1609585585] 'process raft request' (duration: 149.688182ms)","trace[1609585585] 'compare' (duration: 10.66271ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:20:31.233383Z","caller":"traceutil/trace.go:171","msg":"trace[769640502] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"192.158406ms","start":"2026-07-15T11:20:31.041197Z","end":"2026-07-15T11:20:31.233355Z","steps":["trace[769640502] 'process raft request' (duration: 192.099715ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:31.233454Z","caller":"traceutil/trace.go:171","msg":"trace[425359472] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"192.893764ms","start":"2026-07-15T11:20:31.040536Z","end":"2026-07-15T11:20:31.233429Z","steps":["trace[425359472] 'process raft request' (duration: 192.650108ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:31.43127Z","caller":"traceutil/trace.go:171","msg":"trace[934829046] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"100.123466ms","start":"2026-07-15T11:20:31.331116Z","end":"2026-07-15T11:20:31.431239Z","steps":["trace[934829046] 'process raft request' (duration: 100.036244ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:31.431281Z","caller":"traceutil/trace.go:171","msg":"trace[1068066428] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"101.547742ms","start":"2026-07-15T11:20:31.329712Z","end":"2026-07-15T11:20:31.43126Z","steps":["trace[1068066428] 'process raft request' (duration: 100.786393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:49.928182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.503694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T11:20:49.928317Z","caller":"traceutil/trace.go:171","msg":"trace[1345883757] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"211.150949ms","start":"2026-07-15T11:20:49.717144Z","end":"2026-07-15T11:20:49.928295Z","steps":["trace[1345883757] 'process raft request' (duration: 23.3882ms)","trace[1345883757] 'compare' (duration: 187.329509ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:20:50.988644Z","caller":"traceutil/trace.go:171","msg":"trace[841542125] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"220.690612ms","start":"2026-07-15T11:20:50.767899Z","end":"2026-07-15T11:20:50.98859Z","steps":["trace[841542125] 'read index received' (duration: 220.494447ms)","trace[841542125] 'applied index is now lower than readState.Index' (duration: 194.975µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:20:50.988717Z","caller":"traceutil/trace.go:171","msg":"trace[1126292997] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"226.488792ms","start":"2026-07-15T11:20:50.762205Z","end":"2026-07-15T11:20:50.988693Z","steps":["trace[1126292997] 'process raft request' (duration: 226.215016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:50.988918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.998169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T11:20:50.988952Z","caller":"traceutil/trace.go:171","msg":"trace[1158307128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"221.096741ms","start":"2026-07-15T11:20:50.767844Z","end":"2026-07-15T11:20:50.988941Z","steps":["trace[1158307128] 'agreement among raft nodes before linearized reading' (duration: 220.930457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:50.988999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.769339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-15T11:20:50.989045Z","caller":"traceutil/trace.go:171","msg":"trace[1848351678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1272; }","duration":"210.815031ms","start":"2026-07-15T11:20:50.778205Z","end":"2026-07-15T11:20:50.98902Z","steps":["trace[1848351678] 'agreement among raft nodes before linearized reading' (duration: 210.704279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:50.989058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.417461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T11:20:50.989152Z","caller":"traceutil/trace.go:171","msg":"trace[2000972725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1272; }","duration":"210.547075ms","start":"2026-07-15T11:20:50.778578Z","end":"2026-07-15T11:20:50.989125Z","steps":["trace[2000972725] 'agreement among raft nodes before linearized reading' (duration: 210.285738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:50.98905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.153854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-15T11:20:50.989249Z","caller":"traceutil/trace.go:171","msg":"trace[1200238471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1272; }","duration":"212.411579ms","start":"2026-07-15T11:20:50.776822Z","end":"2026-07-15T11:20:50.989234Z","steps":["trace[1200238471] 'agreement among raft nodes before linearized reading' (duration: 212.002939ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:51.274037Z","caller":"traceutil/trace.go:171","msg":"trace[191607341] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"279.611315ms","start":"2026-07-15T11:20:50.994392Z","end":"2026-07-15T11:20:51.274003Z","steps":["trace[191607341] 'process raft request' (duration: 211.572149ms)","trace[191607341] 'compare' (duration: 67.811501ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:20:51.274295Z","caller":"traceutil/trace.go:171","msg":"trace[1299708032] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"279.872352ms","start":"2026-07-15T11:20:50.994401Z","end":"2026-07-15T11:20:51.274274Z","steps":["trace[1299708032] 'process raft request' (duration: 279.545934ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:51.274383Z","caller":"traceutil/trace.go:171","msg":"trace[1099885363] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"279.79431ms","start":"2026-07-15T11:20:50.99457Z","end":"2026-07-15T11:20:51.274365Z","steps":["trace[1099885363] 'process raft request' (duration: 279.561174ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:51.2744Z","caller":"traceutil/trace.go:171","msg":"trace[454463689] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"278.947329ms","start":"2026-07-15T11:20:50.995416Z","end":"2026-07-15T11:20:51.274364Z","steps":["trace[454463689] 'process raft request' (duration: 278.850157ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:53.44532Z","caller":"traceutil/trace.go:171","msg":"trace[1884431087] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"155.663778ms","start":"2026-07-15T11:20:53.289623Z","end":"2026-07-15T11:20:53.445287Z","steps":["trace[1884431087] 'process raft request' (duration: 155.562326ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:53.445332Z","caller":"traceutil/trace.go:171","msg":"trace[1319348041] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"155.71991ms","start":"2026-07-15T11:20:53.289578Z","end":"2026-07-15T11:20:53.445298Z","steps":["trace[1319348041] 'process raft request' (duration: 96.631871ms)","trace[1319348041] 'compare' (duration: 58.789011ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:20:53.445552Z","caller":"traceutil/trace.go:171","msg":"trace[363208456] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"155.846833ms","start":"2026-07-15T11:20:53.28966Z","end":"2026-07-15T11:20:53.445507Z","steps":["trace[363208456] 'process raft request' (duration: 155.575586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:53.819539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.335316ms","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-07-15T11:20:53.819683Z","caller":"traceutil/trace.go:171","msg":"trace[364537486] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1284; }","duration":"223.50992ms","start":"2026-07-15T11:20:53.596154Z","end":"2026-07-15T11:20:53.819664Z","steps":["trace[364537486] 'range keys from in-memory index tree' (duration: 223.306685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:20:53.819649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.856591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T11:20:53.81992Z","caller":"traceutil/trace.go:171","msg":"trace[761452464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"160.21903ms","start":"2026-07-15T11:20:53.659677Z","end":"2026-07-15T11:20:53.819896Z","steps":["trace[761452464] 'range keys from in-memory index tree' (duration: 159.773828ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:20:54.320349Z","caller":"traceutil/trace.go:171","msg":"trace[866059908] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"128.108349ms","start":"2026-07-15T11:20:54.192208Z","end":"2026-07-15T11:20:54.320316Z","steps":["trace[866059908] 'process raft request' (duration: 127.881483ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:21:06.509261Z","caller":"traceutil/trace.go:171","msg":"trace[1399546976] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"125.151111ms","start":"2026-07-15T11:21:06.384073Z","end":"2026-07-15T11:21:06.509224Z","steps":["trace[1399546976] 'process raft request' (duration: 124.941925ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:21:06.92989Z","caller":"traceutil/trace.go:171","msg":"trace[434427733] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1442; }","duration":"270.243506ms","start":"2026-07-15T11:21:06.659619Z","end":"2026-07-15T11:21:06.929862Z","steps":["trace[434427733] 'read index received' (duration: 270.00971ms)","trace[434427733] 'applied index is now lower than readState.Index' (duration: 232.626µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:21:06.930006Z","caller":"traceutil/trace.go:171","msg":"trace[39050363] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"353.241958ms","start":"2026-07-15T11:21:06.576718Z","end":"2026-07-15T11:21:06.92996Z","steps":["trace[39050363] 'process raft request' (duration: 352.976742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:21:06.930093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.44042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T11:21:06.930166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.278422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-15T11:21:06.93017Z","caller":"traceutil/trace.go:171","msg":"trace[1982407652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1388; }","duration":"270.582754ms","start":"2026-07-15T11:21:06.659567Z","end":"2026-07-15T11:21:06.930149Z","steps":["trace[1982407652] 'agreement among raft nodes before linearized reading' (duration: 270.42773ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:21:06.930214Z","caller":"traceutil/trace.go:171","msg":"trace[1298877629] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1388; }","duration":"269.394215ms","start":"2026-07-15T11:21:06.660801Z","end":"2026-07-15T11:21:06.930195Z","steps":["trace[1298877629] 'agreement among raft nodes before linearized reading' (duration: 269.21339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:21:06.930267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:21:06.576683Z","time spent":"353.473174ms","remote":"127.0.0.1:59086","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-07-15T11:21:07.787074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.251318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T11:21:07.787588Z","caller":"traceutil/trace.go:171","msg":"trace[1378376359] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1443; }","duration":"347.261962ms","start":"2026-07-15T11:21:07.440305Z","end":"2026-07-15T11:21:07.787567Z","steps":["trace[1378376359] 'read index received' (duration: 44.652738ms)","trace[1378376359] 'applied index is now lower than readState.Index' (duration: 302.607744ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:21:07.787618Z","caller":"traceutil/trace.go:171","msg":"trace[1167734906] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"666.406798ms","start":"2026-07-15T11:21:07.121182Z","end":"2026-07-15T11:21:07.787589Z","steps":["trace[1167734906] 'process raft request' (duration: 666.004439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:21:07.787696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:21:07.121147Z","time spent":"666.508981ms","remote":"127.0.0.1:59086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T11:21:07.787801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.503488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-15T11:21:07.787818Z","caller":"traceutil/trace.go:171","msg":"trace[12937341] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"852.221376ms","start":"2026-07-15T11:21:06.935587Z","end":"2026-07-15T11:21:07.787809Z","steps":["trace[12937341] 'process raft request' (duration: 489.108387ms)","trace[12937341] 'compare' (duration: 361.327575ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T11:21:07.787839Z","caller":"traceutil/trace.go:171","msg":"trace[456408578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1390; }","duration":"347.58812ms","start":"2026-07-15T11:21:07.440241Z","end":"2026-07-15T11:21:07.787829Z","steps":["trace[456408578] 'agreement among raft nodes before linearized reading' (duration: 347.437156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:21:07.787858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:21:06.935562Z","time spent":"852.275367ms","remote":"127.0.0.1:59086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T11:21:07.787872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T11:21:07.440213Z","time spent":"347.651332ms","remote":"127.0.0.1:59086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T11:21:07.787928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.623395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T11:21:07.788022Z","caller":"traceutil/trace.go:171","msg":"trace[1034503907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1390; }","duration":"277.775298ms","start":"2026-07-15T11:21:07.510225Z","end":"2026-07-15T11:21:07.788Z","steps":["trace[1034503907] 'agreement among raft nodes before linearized reading' (duration: 277.484521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:21:07.788135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.884512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-07-15T11:21:07.788149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.156361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T11:21:07.788171Z","caller":"traceutil/trace.go:171","msg":"trace[78114313] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1390; }","duration":"275.971165ms","start":"2026-07-15T11:21:07.512192Z","end":"2026-07-15T11:21:07.788163Z","steps":["trace[78114313] 'agreement among raft nodes before linearized reading' (duration: 275.875043ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:21:07.788193Z","caller":"traceutil/trace.go:171","msg":"trace[1042690364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1390; }","duration":"274.235303ms","start":"2026-07-15T11:21:07.513946Z","end":"2026-07-15T11:21:07.788181Z","steps":["trace[1042690364] 'agreement among raft nodes before linearized reading' (duration: 274.11958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:21:07.788103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.905877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-07-15T11:21:07.788202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.05077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T11:21:07.788237Z","caller":"traceutil/trace.go:171","msg":"trace[746109515] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1390; }","duration":"151.106762ms","start":"2026-07-15T11:21:07.637123Z","end":"2026-07-15T11:21:07.788229Z","steps":["trace[746109515] 'agreement among raft nodes before linearized reading' (duration: 150.639041ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T11:21:07.788243Z","caller":"traceutil/trace.go:171","msg":"trace[1888046509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1390; }","duration":"128.140553ms","start":"2026-07-15T11:21:07.660092Z","end":"2026-07-15T11:21:07.788233Z","steps":["trace[1888046509] 'agreement among raft nodes before linearized reading' (duration: 128.074581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T11:21:07.788501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.850743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-15T11:21:07.788658Z","caller":"traceutil/trace.go:171","msg":"trace[1301474776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1390; }","duration":"274.017087ms","start":"2026-07-15T11:21:07.514584Z","end":"2026-07-15T11:21:07.788601Z","steps":["trace[1301474776] 'agreement among raft nodes before linearized reading' (duration: 273.637438ms)"],"step_count":1}