{"level":"warn","ts":"2026-05-29T05:40:29.566316Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T05:40:29.566437Z","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-05-29T05:40:29.566521Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T05:40:29.566529Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-05-29T05:40:29.566551Z","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-05-29T05:40:29.567422Z","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-05-29T05:40:29.567506Z","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-05-29T05:40:29.571546Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.857437ms"} {"level":"info","ts":"2026-05-29T05:40:29.578363Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-05-29T05:40:29.578482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T05:40:29.578513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-05-29T05:40:29.578547Z","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-05-29T05:40:29.578561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-05-29T05:40:29.578626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-05-29T05:40:29.586676Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T05:40:29.588191Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T05:40:29.589381Z","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-05-29T05:40:29.594543Z","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-05-29T05:40:29.594665Z","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-05-29T05:40:29.59476Z","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-05-29T05:40:29.594771Z","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-05-29T05:40:29.596907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-05-29T05:40:29.597394Z","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-05-29T05:40:29.595218Z","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-05-29T05:40:29.605165Z","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-05-29T05:40:29.605367Z","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-05-29T05:40:29.605385Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T05:40:29.605476Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-05-29T05:40:29.605483Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-05-29T05:40:30.379475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T05:40:30.379561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T05:40:30.379625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-05-29T05:40:30.379648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-05-29T05:40:30.37966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-05-29T05:40:30.379678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-05-29T05:40:30.379693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-05-29T05:40:30.387645Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:40:30.388853Z","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-05-29T05:40:30.389125Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:40:30.389386Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:40:30.389678Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T05:40:30.389699Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T05:40:30.390628Z","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-05-29T05:40:30.390703Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:40:30.390722Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:40:30.391677Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T05:40:30.392665Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"warn","ts":"2026-05-29T05:40:33.100676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.572889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-29T05:40:33.100921Z","caller":"traceutil/trace.go:171","msg":"trace[320733321] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"216.169248ms","start":"2026-05-29T05:40:32.884723Z","end":"2026-05-29T05:40:33.100892Z","steps":["trace[320733321] 'process raft request' (duration: 112.568087ms)","trace[320733321] 'compare' (duration: 102.337405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:40:33.724625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.440985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-29T05:40:33.724777Z","caller":"traceutil/trace.go:171","msg":"trace[699740392] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"529.902559ms","start":"2026-05-29T05:40:33.194849Z","end":"2026-05-29T05:40:33.724751Z","steps":["trace[699740392] 'process raft request' (duration: 99.244021ms)","trace[699740392] 'compare' (duration: 430.215761ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:40:33.724873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:40:33.194837Z","time spent":"530.003785ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":686,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:40:33.986509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.380221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-29T05:40:33.986706Z","caller":"traceutil/trace.go:171","msg":"trace[1899191950] linearizableReadLoop","detail":"{readStateIndex:107; appliedIndex:106; }","duration":"167.647597ms","start":"2026-05-29T05:40:33.819022Z","end":"2026-05-29T05:40:33.986669Z","steps":["trace[1899191950] 'read index received' (duration: 19.998679ms)","trace[1899191950] 'applied index is now lower than readState.Index' (duration: 147.647018ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:40:33.986726Z","caller":"traceutil/trace.go:171","msg":"trace[679181266] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"255.487408ms","start":"2026-05-29T05:40:33.731203Z","end":"2026-05-29T05:40:33.986691Z","steps":["trace[679181266] 'process raft request' (duration: 107.810939ms)","trace[679181266] 'compare' (duration: 147.20528ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:40:33.986885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.853199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T05:40:33.986945Z","caller":"traceutil/trace.go:171","msg":"trace[258346250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:103; }","duration":"167.983376ms","start":"2026-05-29T05:40:33.818943Z","end":"2026-05-29T05:40:33.986927Z","steps":["trace[258346250] 'agreement among raft nodes before linearized reading' (duration: 167.812866ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:40:34.291791Z","caller":"traceutil/trace.go:171","msg":"trace[1510563355] transaction","detail":"{read_only:false; response_revision:104; number_of_response:1; }","duration":"300.345427ms","start":"2026-05-29T05:40:33.991409Z","end":"2026-05-29T05:40:34.291755Z","steps":["trace[1510563355] 'process raft request' (duration: 285.642494ms)","trace[1510563355] 'compare' (duration: 14.544994ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:40:34.292087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:40:33.991387Z","time spent":"300.497196ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1368,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:40:36.668386Z","caller":"traceutil/trace.go:171","msg":"trace[1663190420] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"138.163203ms","start":"2026-05-29T05:40:36.529988Z","end":"2026-05-29T05:40:36.668152Z","steps":["trace[1663190420] 'process raft request' (duration: 67.893333ms)","trace[1663190420] 'compare' (duration: 70.172994ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:40:36.680407Z","caller":"traceutil/trace.go:171","msg":"trace[168923606] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"148.20296ms","start":"2026-05-29T05:40:36.532146Z","end":"2026-05-29T05:40:36.680349Z","steps":["trace[168923606] 'process raft request' (duration: 147.972076ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:40:36.878113Z","caller":"traceutil/trace.go:171","msg":"trace[1438195192] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"106.188747ms","start":"2026-05-29T05:40:36.7719Z","end":"2026-05-29T05:40:36.878088Z","steps":["trace[1438195192] 'process raft request' (duration: 106.141274ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:40:36.878187Z","caller":"traceutil/trace.go:171","msg":"trace[1769349826] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"162.154648ms","start":"2026-05-29T05:40:36.71599Z","end":"2026-05-29T05:40:36.878145Z","steps":["trace[1769349826] 'process raft request' (duration: 116.702484ms)","trace[1769349826] 'compare' (duration: 45.214018ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:40:37.079799Z","caller":"traceutil/trace.go:171","msg":"trace[1232338642] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"129.938722ms","start":"2026-05-29T05:40:36.949801Z","end":"2026-05-29T05:40:37.07974Z","steps":["trace[1232338642] 'process raft request' (duration: 54.975359ms)","trace[1232338642] 'compare' (duration: 74.746719ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:40:37.083875Z","caller":"traceutil/trace.go:171","msg":"trace[1370261796] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"111.649748ms","start":"2026-05-29T05:40:36.972187Z","end":"2026-05-29T05:40:37.083837Z","steps":["trace[1370261796] 'process raft request' (duration: 111.467387ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:40:37.280869Z","caller":"traceutil/trace.go:171","msg":"trace[1885073455] linearizableReadLoop","detail":"{readStateIndex:260; appliedIndex:258; }","duration":"114.708389ms","start":"2026-05-29T05:40:37.166137Z","end":"2026-05-29T05:40:37.280845Z","steps":["trace[1885073455] 'read index received' (duration: 78.279238ms)","trace[1885073455] 'applied index is now lower than readState.Index' (duration: 36.428201ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:40:37.281037Z","caller":"traceutil/trace.go:171","msg":"trace[1163655072] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"116.170839ms","start":"2026-05-29T05:40:37.164831Z","end":"2026-05-29T05:40:37.281002Z","steps":["trace[1163655072] 'process raft request' (duration: 115.968057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:40:37.28109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.921541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T05:40:37.281152Z","caller":"traceutil/trace.go:171","msg":"trace[1450726273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:254; }","duration":"115.04444ms","start":"2026-05-29T05:40:37.166091Z","end":"2026-05-29T05:40:37.281135Z","steps":["trace[1450726273] 'agreement among raft nodes before linearized reading' (duration: 114.862828ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:40:37.281267Z","caller":"traceutil/trace.go:171","msg":"trace[487083449] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"156.481499ms","start":"2026-05-29T05:40:37.124701Z","end":"2026-05-29T05:40:37.281182Z","steps":["trace[487083449] 'process raft request' (duration: 119.76ms)","trace[487083449] 'compare' (duration: 36.182906ms)"],"step_count":2} 2026/05/29 05:40:37 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T05:40:37.915542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.381354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:40:37.915763Z","caller":"traceutil/trace.go:171","msg":"trace[445346182] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"188.739484ms","start":"2026-05-29T05:40:37.727003Z","end":"2026-05-29T05:40:37.915742Z","steps":["trace[445346182] 'process raft request' (duration: 188.665489ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:40:37.915757Z","caller":"traceutil/trace.go:171","msg":"trace[1841928408] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"274.098829ms","start":"2026-05-29T05:40:37.641615Z","end":"2026-05-29T05:40:37.915714Z","steps":["trace[1841928408] 'process raft request' (duration: 22.443047ms)","trace[1841928408] 'compare' (duration: 251.214154ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:40:37.915804Z","caller":"traceutil/trace.go:171","msg":"trace[513615985] linearizableReadLoop","detail":"{readStateIndex:266; appliedIndex:265; }","duration":"207.43958ms","start":"2026-05-29T05:40:37.708334Z","end":"2026-05-29T05:40:37.915773Z","steps":["trace[513615985] 'read index received' (duration: 43.714µs)","trace[513615985] 'applied index is now lower than readState.Index' (duration: 207.393286ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:40:37.91625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.883877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:40:37.916323Z","caller":"traceutil/trace.go:171","msg":"trace[901877932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:261; }","duration":"208.026855ms","start":"2026-05-29T05:40:37.708279Z","end":"2026-05-29T05:40:37.916306Z","steps":["trace[901877932] 'agreement among raft nodes before linearized reading' (duration: 207.714136ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:40:37.934601Z","caller":"traceutil/trace.go:171","msg":"trace[751496383] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"114.824696ms","start":"2026-05-29T05:40:37.819758Z","end":"2026-05-29T05:40:37.934582Z","steps":["trace[751496383] 'process raft request' (duration: 114.670336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:40:37.934857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.664322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:40:37.934959Z","caller":"traceutil/trace.go:171","msg":"trace[733871812] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:265; }","duration":"207.754428ms","start":"2026-05-29T05:40:37.727159Z","end":"2026-05-29T05:40:37.934914Z","steps":["trace[733871812] 'agreement among raft nodes before linearized reading' (duration: 207.6285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:40:37.935369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.647366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-29T05:40:37.93549Z","caller":"traceutil/trace.go:171","msg":"trace[277240153] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:265; }","duration":"209.814876ms","start":"2026-05-29T05:40:37.725633Z","end":"2026-05-29T05:40:37.935447Z","steps":["trace[277240153] 'agreement among raft nodes before linearized reading' (duration: 209.082651ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:40:48.708071Z","caller":"traceutil/trace.go:171","msg":"trace[2064667253] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"382.918833ms","start":"2026-05-29T05:40:48.325132Z","end":"2026-05-29T05:40:48.70805Z","steps":["trace[2064667253] 'process raft request' (duration: 382.763013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:40:48.708445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:40:48.325102Z","time spent":"383.045361ms","remote":"127.0.0.1:44304","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-05-29T05:40:54.565568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.725506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:40:54.565692Z","caller":"traceutil/trace.go:171","msg":"trace[821286078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"498.901087ms","start":"2026-05-29T05:40:54.066763Z","end":"2026-05-29T05:40:54.565664Z","steps":["trace[821286078] 'range keys from in-memory index tree' (duration: 498.64128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:40:54.565744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:40:54.066748Z","time spent":"498.986273ms","remote":"127.0.0.1:44058","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-05-29T05:40:54.565727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.285841ms","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-05-29T05:40:54.565735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.424208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:40:54.565834Z","caller":"traceutil/trace.go:171","msg":"trace[1887721754] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:425; }","duration":"523.463523ms","start":"2026-05-29T05:40:54.042343Z","end":"2026-05-29T05:40:54.565806Z","steps":["trace[1887721754] 'range keys from in-memory index tree' (duration: 523.114089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:40:54.565899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:40:54.042305Z","time spent":"523.58325ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:40:54.565861Z","caller":"traceutil/trace.go:171","msg":"trace[782331903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:425; }","duration":"664.633522ms","start":"2026-05-29T05:40:53.901201Z","end":"2026-05-29T05:40:54.565835Z","steps":["trace[782331903] 'range keys from in-memory index tree' (duration: 664.254147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:40:54.566028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:40:53.90118Z","time spent":"664.836446ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:40:56.86154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.685778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:40:56.862085Z","caller":"traceutil/trace.go:171","msg":"trace[165157579] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"279.586993ms","start":"2026-05-29T05:40:56.582381Z","end":"2026-05-29T05:40:56.861968Z","steps":["trace[165157579] 'process raft request' (duration: 65.364117ms)","trace[165157579] 'compare' (duration: 213.524208ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:40:59.244658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.272161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:40:59.244875Z","caller":"traceutil/trace.go:171","msg":"trace[1368623620] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"613.983708ms","start":"2026-05-29T05:40:58.630845Z","end":"2026-05-29T05:40:59.244829Z","steps":["trace[1368623620] 'process raft request' (duration: 395.435998ms)","trace[1368623620] 'compare' (duration: 218.046396ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:40:59.244926Z","caller":"traceutil/trace.go:171","msg":"trace[2051106705] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"377.866651ms","start":"2026-05-29T05:40:58.867032Z","end":"2026-05-29T05:40:59.244899Z","steps":["trace[2051106705] 'read index received' (duration: 159.245295ms)","trace[2051106705] 'applied index is now lower than readState.Index' (duration: 218.618526ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:40:59.245087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:40:58.63082Z","time spent":"614.1735ms","remote":"127.0.0.1:44498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:40:59.245107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.059194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:40:59.245145Z","caller":"traceutil/trace.go:171","msg":"trace[1751254824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:438; }","duration":"378.107508ms","start":"2026-05-29T05:40:58.867025Z","end":"2026-05-29T05:40:59.245132Z","steps":["trace[1751254824] 'agreement among raft nodes before linearized reading' (duration: 377.970478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:40:59.245181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:40:58.866954Z","time spent":"378.214514ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:40:59.246304Z","caller":"traceutil/trace.go:171","msg":"trace[1564216821] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"347.663411ms","start":"2026-05-29T05:40:58.898626Z","end":"2026-05-29T05:40:59.246289Z","steps":["trace[1564216821] 'process raft request' (duration: 346.400945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:40:59.246412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:40:58.898596Z","time spent":"347.763668ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:40:59.460503Z","caller":"traceutil/trace.go:171","msg":"trace[2050841263] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"151.921752ms","start":"2026-05-29T05:40:59.308539Z","end":"2026-05-29T05:40:59.46046Z","steps":["trace[2050841263] 'process raft request' (duration: 88.241203ms)","trace[2050841263] 'compare' (duration: 63.379908ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:40:59.460571Z","caller":"traceutil/trace.go:171","msg":"trace[1038643252] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"150.423861ms","start":"2026-05-29T05:40:59.310096Z","end":"2026-05-29T05:40:59.46052Z","steps":["trace[1038643252] 'process raft request' (duration: 150.26912ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:40:59.460616Z","caller":"traceutil/trace.go:171","msg":"trace[1204406082] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"149.189407ms","start":"2026-05-29T05:40:59.311389Z","end":"2026-05-29T05:40:59.460578Z","steps":["trace[1204406082] 'process raft request' (duration: 149.039706ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:40:59.460901Z","caller":"traceutil/trace.go:171","msg":"trace[314511413] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"147.546475ms","start":"2026-05-29T05:40:59.313026Z","end":"2026-05-29T05:40:59.460572Z","steps":["trace[314511413] 'process raft request' (duration: 147.47174ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:40:59.474599Z","caller":"traceutil/trace.go:171","msg":"trace[566359349] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"139.376671ms","start":"2026-05-29T05:40:59.335191Z","end":"2026-05-29T05:40:59.474567Z","steps":["trace[566359349] 'process raft request' (duration: 139.241391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:40:59.999683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.823403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:40:59.999783Z","caller":"traceutil/trace.go:171","msg":"trace[1135003808] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"492.769724ms","start":"2026-05-29T05:40:59.506996Z","end":"2026-05-29T05:40:59.999766Z","steps":["trace[1135003808] 'process raft request' (duration: 330.777499ms)","trace[1135003808] 'compare' (duration: 161.702855ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:40:59.999837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:40:59.506969Z","time spent":"492.841329ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4013,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:41:00.783539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.582248ms","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-05-29T05:41:00.783703Z","caller":"traceutil/trace.go:171","msg":"trace[744402707] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:452; }","duration":"683.762681ms","start":"2026-05-29T05:41:00.09991Z","end":"2026-05-29T05:41:00.783673Z","steps":["trace[744402707] 'range keys from in-memory index tree' (duration: 683.561557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:00.783831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.478254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:41:00.784127Z","caller":"traceutil/trace.go:171","msg":"trace[1240535851] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"1.21655101s","start":"2026-05-29T05:40:59.567535Z","end":"2026-05-29T05:41:00.784086Z","steps":["trace[1240535851] 'process raft request' (duration: 635.721482ms)","trace[1240535851] 'compare' (duration: 580.324873ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:00.784317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:40:59.567516Z","time spent":"1.21670666s","remote":"127.0.0.1:44498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:41:00.784632Z","caller":"traceutil/trace.go:171","msg":"trace[1174058586] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:466; }","duration":"1.059689384s","start":"2026-05-29T05:40:59.724931Z","end":"2026-05-29T05:41:00.784621Z","steps":["trace[1174058586] 'read index received' (duration: 112.881775ms)","trace[1174058586] 'applied index is now lower than readState.Index' (duration: 946.806208ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:00.784751Z","caller":"traceutil/trace.go:171","msg":"trace[108234717] transaction","detail":"{read_only:false; number_of_response:1; response_revision:454; }","duration":"782.902985ms","start":"2026-05-29T05:41:00.00184Z","end":"2026-05-29T05:41:00.784743Z","steps":["trace[108234717] 'process raft request' (duration: 782.23938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:00.78486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:00.001816Z","time spent":"782.954368ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-05-29T05:41:00.785064Z","caller":"traceutil/trace.go:171","msg":"trace[1967200805] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"780.297879ms","start":"2026-05-29T05:41:00.004752Z","end":"2026-05-29T05:41:00.78505Z","steps":["trace[1967200805] 'process raft request' (duration: 779.475113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:00.785119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:00.004726Z","time spent":"780.365573ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:41:01.953326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169018817s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-29T05:41:01.95357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.228598163s","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-05-29T05:41:01.953672Z","caller":"traceutil/trace.go:171","msg":"trace[2099129934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:455; }","duration":"2.228761324s","start":"2026-05-29T05:40:59.724884Z","end":"2026-05-29T05:41:01.953645Z","steps":["trace[2099129934] 'agreement among raft nodes before linearized reading' (duration: 1.059824402s)","trace[2099129934] 'range keys from in-memory index tree' (duration: 1.168781771s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:01.953713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:40:59.72486Z","time spent":"2.22884379s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T05:41:01.954114Z","caller":"traceutil/trace.go:171","msg":"trace[2046859534] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:470; }","duration":"1.169381102s","start":"2026-05-29T05:41:00.784664Z","end":"2026-05-29T05:41:01.954045Z","steps":["trace[2046859534] 'read index received' (duration: 60.815µs)","trace[2046859534] 'applied index is now lower than readState.Index' (duration: 1.169317627s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:01.955396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262278233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:41:01.955502Z","caller":"traceutil/trace.go:171","msg":"trace[1635004589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:455; }","duration":"1.262523859s","start":"2026-05-29T05:41:00.692951Z","end":"2026-05-29T05:41:01.955475Z","steps":["trace[1635004589] 'agreement among raft nodes before linearized reading' (duration: 1.261279175s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:01.955516Z","caller":"traceutil/trace.go:171","msg":"trace[250448974] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"1.164824152s","start":"2026-05-29T05:41:00.79066Z","end":"2026-05-29T05:41:01.955485Z","steps":["trace[250448974] 'process raft request' (duration: 1.162801284s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:01.955561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:00.692926Z","time spent":"1.262624546s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:41:01.955627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:00.79064Z","time spent":"1.164929269s","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:41:01.955744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.888112953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:41:01.955771Z","caller":"traceutil/trace.go:171","msg":"trace[1410089682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"1.888182707s","start":"2026-05-29T05:41:00.06758Z","end":"2026-05-29T05:41:01.955762Z","steps":["trace[1410089682] 'agreement among raft nodes before linearized reading' (duration: 1.886867917s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:01.955788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:00.06756Z","time spent":"1.88822436s","remote":"127.0.0.1:44058","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-05-29T05:41:02.379561Z","caller":"traceutil/trace.go:171","msg":"trace[1027180652] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"1.588816007s","start":"2026-05-29T05:41:00.790715Z","end":"2026-05-29T05:41:02.379531Z","steps":["trace[1027180652] 'process raft request' (duration: 1.586764437s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:02.379641Z","caller":"traceutil/trace.go:171","msg":"trace[507685530] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:472; }","duration":"425.424832ms","start":"2026-05-29T05:41:01.954199Z","end":"2026-05-29T05:41:02.379624Z","steps":["trace[507685530] 'read index received' (duration: 423.367242ms)","trace[507685530] 'applied index is now lower than readState.Index' (duration: 2.05672ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:02.37972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:00.790685Z","time spent":"1.588961607s","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:41:02.379698Z","caller":"traceutil/trace.go:171","msg":"trace[1581000616] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"1.586128904s","start":"2026-05-29T05:41:00.793532Z","end":"2026-05-29T05:41:02.379661Z","steps":["trace[1581000616] 'process raft request' (duration: 1.585994265s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:02.379877Z","caller":"traceutil/trace.go:171","msg":"trace[150100103] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"1.585594807s","start":"2026-05-29T05:41:00.794268Z","end":"2026-05-29T05:41:02.379862Z","steps":["trace[150100103] 'process raft request' (duration: 1.585315778s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:02.379888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:00.793521Z","time spent":"1.586259463s","remote":"127.0.0.1:44498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:41:02.379862Z","caller":"traceutil/trace.go:171","msg":"trace[1582015558] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"1.588627104s","start":"2026-05-29T05:41:00.791172Z","end":"2026-05-29T05:41:02.379799Z","steps":["trace[1582015558] 'process raft request' (duration: 1.588312193s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:02.379987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:00.794253Z","time spent":"1.585670434s","remote":"127.0.0.1:44484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:41:02.380153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:00.791161Z","time spent":"1.58885642s","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:41:02.380264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.403558ms","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-05-29T05:41:02.380183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071762991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:41:02.380306Z","caller":"traceutil/trace.go:171","msg":"trace[1934534192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:460; }","duration":"817.494445ms","start":"2026-05-29T05:41:01.562799Z","end":"2026-05-29T05:41:02.380294Z","steps":["trace[1934534192] 'agreement among raft nodes before linearized reading' (duration: 817.407809ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:02.380329Z","caller":"traceutil/trace.go:171","msg":"trace[683685445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:460; }","duration":"1.071952314s","start":"2026-05-29T05:41:01.308348Z","end":"2026-05-29T05:41:02.380301Z","steps":["trace[683685445] 'agreement among raft nodes before linearized reading' (duration: 1.071685196s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:02.380337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:01.562773Z","time spent":"817.557029ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:41:02.380377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:01.30832Z","time spent":"1.07204613s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:41:02.413302Z","caller":"traceutil/trace.go:171","msg":"trace[86480081] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"453.092308ms","start":"2026-05-29T05:41:01.960184Z","end":"2026-05-29T05:41:02.413277Z","steps":["trace[86480081] 'process raft request' (duration: 453.005662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:02.41365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:01.960172Z","time spent":"453.344836ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:41:02.413651Z","caller":"traceutil/trace.go:171","msg":"trace[263919155] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"455.391746ms","start":"2026-05-29T05:41:01.958173Z","end":"2026-05-29T05:41:02.413565Z","steps":["trace[263919155] 'process raft request' (duration: 454.759132ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:02.414008Z","caller":"traceutil/trace.go:171","msg":"trace[440538127] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"453.200916ms","start":"2026-05-29T05:41:01.960766Z","end":"2026-05-29T05:41:02.413967Z","steps":["trace[440538127] 'process raft request' (duration: 452.460994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:02.41421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:01.960734Z","time spent":"453.381019ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:41:02.414276Z","caller":"traceutil/trace.go:171","msg":"trace[1939752524] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"452.695541ms","start":"2026-05-29T05:41:01.961432Z","end":"2026-05-29T05:41:02.414128Z","steps":["trace[1939752524] 'process raft request' (duration: 452.218709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:02.414604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:01.961419Z","time spent":"453.018962ms","remote":"127.0.0.1:44498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:41:02.414818Z","caller":"traceutil/trace.go:171","msg":"trace[613848741] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"451.399443ms","start":"2026-05-29T05:41:01.9634Z","end":"2026-05-29T05:41:02.4148Z","steps":["trace[613848741] 'process raft request' (duration: 450.651272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:02.415723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:01.963394Z","time spent":"452.277053ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:41:02.415239Z","caller":"traceutil/trace.go:171","msg":"trace[468198152] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"121.850934ms","start":"2026-05-29T05:41:02.293366Z","end":"2026-05-29T05:41:02.415217Z","steps":["trace[468198152] 'process raft request' (duration: 121.289345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:02.415194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.025976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:41:02.416358Z","caller":"traceutil/trace.go:171","msg":"trace[344783572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"459.515868ms","start":"2026-05-29T05:41:01.956802Z","end":"2026-05-29T05:41:02.416318Z","steps":["trace[344783572] 'agreement among raft nodes before linearized reading' (duration: 458.003323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:02.41541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:01.958158Z","time spent":"455.898931ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:41:02.416593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:01.956782Z","time spent":"459.749884ms","remote":"127.0.0.1:44044","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-05-29T05:41:02.63549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.906504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:41:02.635693Z","caller":"traceutil/trace.go:171","msg":"trace[1130376310] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:482; }","duration":"211.204963ms","start":"2026-05-29T05:41:02.424405Z","end":"2026-05-29T05:41:02.635609Z","steps":["trace[1130376310] 'read index received' (duration: 754.002µs)","trace[1130376310] 'applied index is now lower than readState.Index' (duration: 210.449391ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:02.635966Z","caller":"traceutil/trace.go:171","msg":"trace[142519886] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"213.281435ms","start":"2026-05-29T05:41:02.42261Z","end":"2026-05-29T05:41:02.635891Z","steps":["trace[142519886] 'process raft request' (duration: 100.518374ms)","trace[142519886] 'compare' (duration: 111.567449ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:02.636561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.827189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"warn","ts":"2026-05-29T05:41:02.636747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.31609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:41:02.636809Z","caller":"traceutil/trace.go:171","msg":"trace[118585240] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:472; }","duration":"204.109847ms","start":"2026-05-29T05:41:02.432679Z","end":"2026-05-29T05:41:02.636789Z","steps":["trace[118585240] 'agreement among raft nodes before linearized reading' (duration: 203.55645ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:02.636873Z","caller":"traceutil/trace.go:171","msg":"trace[122509857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"212.47528ms","start":"2026-05-29T05:41:02.424355Z","end":"2026-05-29T05:41:02.636831Z","steps":["trace[122509857] 'agreement among raft nodes before linearized reading' (duration: 211.693876ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:04.698033Z","caller":"traceutil/trace.go:171","msg":"trace[1302971686] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:489; }","duration":"269.176698ms","start":"2026-05-29T05:41:04.428821Z","end":"2026-05-29T05:41:04.697998Z","steps":["trace[1302971686] 'read index received' (duration: 61.574393ms)","trace[1302971686] 'applied index is now lower than readState.Index' (duration: 207.601605ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:04.698087Z","caller":"traceutil/trace.go:171","msg":"trace[2026229962] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"269.9237ms","start":"2026-05-29T05:41:04.428139Z","end":"2026-05-29T05:41:04.698062Z","steps":["trace[2026229962] 'process raft request' (duration: 269.702295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:04.698243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.362431ms","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-05-29T05:41:04.698281Z","caller":"traceutil/trace.go:171","msg":"trace[1536086333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"269.476419ms","start":"2026-05-29T05:41:04.428788Z","end":"2026-05-29T05:41:04.698264Z","steps":["trace[1536086333] 'agreement among raft nodes before linearized reading' (duration: 269.365711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:04.69829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.423135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:41:04.698426Z","caller":"traceutil/trace.go:171","msg":"trace[1936896669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"269.595717ms","start":"2026-05-29T05:41:04.428803Z","end":"2026-05-29T05:41:04.698399Z","steps":["trace[1936896669] 'agreement among raft nodes before linearized reading' (duration: 269.315628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:11.119333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.243489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:41:11.119698Z","caller":"traceutil/trace.go:171","msg":"trace[1772993294] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"134.935994ms","start":"2026-05-29T05:41:10.984738Z","end":"2026-05-29T05:41:11.119674Z","steps":["trace[1772993294] 'read index received' (duration: 51.884µs)","trace[1772993294] 'applied index is now lower than readState.Index' (duration: 134.88247ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:11.1198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.062543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:41:11.12003Z","caller":"traceutil/trace.go:171","msg":"trace[1164661202] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:491; }","duration":"135.144148ms","start":"2026-05-29T05:41:10.984676Z","end":"2026-05-29T05:41:11.11982Z","steps":["trace[1164661202] 'agreement among raft nodes before linearized reading' (duration: 135.079413ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:11.120156Z","caller":"traceutil/trace.go:171","msg":"trace[2054432295] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"299.396096ms","start":"2026-05-29T05:41:10.820714Z","end":"2026-05-29T05:41:11.12011Z","steps":["trace[2054432295] 'process raft request' (duration: 130.21151ms)","trace[2054432295] 'compare' (duration: 168.097739ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:13.172396Z","caller":"traceutil/trace.go:171","msg":"trace[1818740673] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"352.306977ms","start":"2026-05-29T05:41:12.820066Z","end":"2026-05-29T05:41:13.172373Z","steps":["trace[1818740673] 'process raft request' (duration: 352.168368ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:13.172528Z","caller":"traceutil/trace.go:171","msg":"trace[1729517547] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"322.863802ms","start":"2026-05-29T05:41:12.849617Z","end":"2026-05-29T05:41:13.17248Z","steps":["trace[1729517547] 'read index received' (duration: 322.729612ms)","trace[1729517547] 'applied index is now lower than readState.Index' (duration: 130.66µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:13.172783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.131442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:41:13.17338Z","caller":"traceutil/trace.go:171","msg":"trace[315034843] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"323.751235ms","start":"2026-05-29T05:41:12.849562Z","end":"2026-05-29T05:41:13.173314Z","steps":["trace[315034843] 'agreement among raft nodes before linearized reading' (duration: 323.056786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:13.173466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:12.849541Z","time spent":"323.889074ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:41:13.173751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:12.820049Z","time spent":"353.081162ms","remote":"127.0.0.1:44304","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-05-29T05:41:13.618058Z","caller":"traceutil/trace.go:171","msg":"trace[693223487] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"613.721929ms","start":"2026-05-29T05:41:13.004298Z","end":"2026-05-29T05:41:13.61802Z","steps":["trace[693223487] 'process raft request' (duration: 613.309529ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:13.61814Z","caller":"traceutil/trace.go:171","msg":"trace[171812454] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:546; }","duration":"444.867693ms","start":"2026-05-29T05:41:13.173234Z","end":"2026-05-29T05:41:13.618101Z","steps":["trace[171812454] 'read index received' (duration: 443.911106ms)","trace[171812454] 'applied index is now lower than readState.Index' (duration: 955.507µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:13.618134Z","caller":"traceutil/trace.go:171","msg":"trace[1630610044] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"635.674626ms","start":"2026-05-29T05:41:12.982396Z","end":"2026-05-29T05:41:13.618071Z","steps":["trace[1630610044] 'process raft request' (duration: 635.02214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:13.618232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:13.004267Z","time spent":"613.881601ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:41:13.618249Z","caller":"traceutil/trace.go:171","msg":"trace[529393872] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"629.101043ms","start":"2026-05-29T05:41:12.98911Z","end":"2026-05-29T05:41:13.618211Z","steps":["trace[529393872] 'process raft request' (duration: 628.377872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:13.618335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.261504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:41:13.618371Z","caller":"traceutil/trace.go:171","msg":"trace[1265543830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"551.35846ms","start":"2026-05-29T05:41:13.067Z","end":"2026-05-29T05:41:13.618358Z","steps":["trace[1265543830] 'agreement among raft nodes before linearized reading' (duration: 551.223191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:13.618378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:12.989103Z","time spent":"629.203441ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:41:13.618402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:13.066984Z","time spent":"551.411724ms","remote":"127.0.0.1:44058","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-05-29T05:41:13.618386Z","caller":"traceutil/trace.go:171","msg":"trace[651118178] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"622.401892ms","start":"2026-05-29T05:41:12.995973Z","end":"2026-05-29T05:41:13.618375Z","steps":["trace[651118178] 'process raft request' (duration: 621.593654ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:13.618361Z","caller":"traceutil/trace.go:171","msg":"trace[1667194150] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"640.725393ms","start":"2026-05-29T05:41:12.977584Z","end":"2026-05-29T05:41:13.61831Z","steps":["trace[1667194150] 'process raft request' (duration: 639.566661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:13.61841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:12.98237Z","time spent":"635.893772ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:41:13.618457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:12.995952Z","time spent":"622.481837ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:41:13.618539Z","caller":"traceutil/trace.go:171","msg":"trace[496444101] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"571.654104ms","start":"2026-05-29T05:41:13.046876Z","end":"2026-05-29T05:41:13.61853Z","steps":["trace[496444101] 'process raft request' (duration: 571.055333ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:13.618541Z","caller":"traceutil/trace.go:171","msg":"trace[1563083089] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"605.51868ms","start":"2026-05-29T05:41:13.013012Z","end":"2026-05-29T05:41:13.618531Z","steps":["trace[1563083089] 'process raft request' (duration: 604.675019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:13.618563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:12.977555Z","time spent":"640.913556ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:41:13.618577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:13.046848Z","time spent":"571.710248ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:41:13.618588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:13.012949Z","time spent":"605.616366ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:41:13.618664Z","caller":"traceutil/trace.go:171","msg":"trace[1526853116] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"593.08935ms","start":"2026-05-29T05:41:13.025566Z","end":"2026-05-29T05:41:13.618655Z","steps":["trace[1526853116] 'process raft request' (duration: 592.307364ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:13.618669Z","caller":"traceutil/trace.go:171","msg":"trace[320684361] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"612.779543ms","start":"2026-05-29T05:41:13.00588Z","end":"2026-05-29T05:41:13.61866Z","steps":["trace[320684361] 'process raft request' (duration: 611.768661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:13.618677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.822785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T05:41:13.618706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:13.025538Z","time spent":"593.150105ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:41:13.618709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:13.005872Z","time spent":"612.819245ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:41:13.618724Z","caller":"traceutil/trace.go:171","msg":"trace[2047231360] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"605.614965ms","start":"2026-05-29T05:41:13.013098Z","end":"2026-05-29T05:41:13.618713Z","steps":["trace[2047231360] 'process raft request' (duration: 604.632465ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:13.618736Z","caller":"traceutil/trace.go:171","msg":"trace[1404110305] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"456.058185ms","start":"2026-05-29T05:41:13.162668Z","end":"2026-05-29T05:41:13.618726Z","steps":["trace[1404110305] 'process raft request' (duration: 455.347745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:13.618787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:13.162647Z","time spent":"456.117909ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:41:13.618725Z","caller":"traceutil/trace.go:171","msg":"trace[1421470264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"493.90282ms","start":"2026-05-29T05:41:13.124807Z","end":"2026-05-29T05:41:13.61871Z","steps":["trace[1421470264] 'agreement among raft nodes before linearized reading' (duration: 493.783302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:13.618896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:13.124793Z","time spent":"494.087703ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:41:13.618927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:13.013058Z","time spent":"605.719343ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:41:13.618604Z","caller":"traceutil/trace.go:171","msg":"trace[318722351] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"601.315051ms","start":"2026-05-29T05:41:13.017223Z","end":"2026-05-29T05:41:13.618538Z","steps":["trace[318722351] 'process raft request' (duration: 600.574048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:13.619009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:13.017215Z","time spent":"601.769554ms","remote":"127.0.0.1:44088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:41:13.623677Z","caller":"traceutil/trace.go:171","msg":"trace[1936714917] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"446.663531ms","start":"2026-05-29T05:41:13.176999Z","end":"2026-05-29T05:41:13.623662Z","steps":["trace[1936714917] 'process raft request' (duration: 446.340797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:13.623772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:13.176946Z","time spent":"446.778829ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:41:17.838386Z","caller":"traceutil/trace.go:171","msg":"trace[1943574011] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"201.350545ms","start":"2026-05-29T05:41:17.637004Z","end":"2026-05-29T05:41:17.838354Z","steps":["trace[1943574011] 'read index received' (duration: 198.528054ms)","trace[1943574011] 'applied index is now lower than readState.Index' (duration: 2.821531ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:17.838593Z","caller":"traceutil/trace.go:171","msg":"trace[106699317] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"352.524087ms","start":"2026-05-29T05:41:17.485902Z","end":"2026-05-29T05:41:17.838426Z","steps":["trace[106699317] 'process raft request' (duration: 350.840377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:17.838823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.799877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:41:17.838968Z","caller":"traceutil/trace.go:171","msg":"trace[1214910017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"202.031804ms","start":"2026-05-29T05:41:17.636897Z","end":"2026-05-29T05:41:17.838929Z","steps":["trace[1214910017] 'agreement among raft nodes before linearized reading' (duration: 201.657768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:17.838954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.52804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T05:41:17.839112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:17.485866Z","time spent":"352.919656ms","remote":"127.0.0.1:44152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:41:17.839186Z","caller":"traceutil/trace.go:171","msg":"trace[1207365500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"188.823151ms","start":"2026-05-29T05:41:17.650345Z","end":"2026-05-29T05:41:17.839168Z","steps":["trace[1207365500] 'agreement among raft nodes before linearized reading' (duration: 188.357908ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:20.464389Z","caller":"traceutil/trace.go:171","msg":"trace[45978890] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"240.19842ms","start":"2026-05-29T05:41:20.22416Z","end":"2026-05-29T05:41:20.464359Z","steps":["trace[45978890] 'process raft request' (duration: 232.931108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:22.244186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.13841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:41:22.244325Z","caller":"traceutil/trace.go:171","msg":"trace[702961851] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"377.211529ms","start":"2026-05-29T05:41:21.867091Z","end":"2026-05-29T05:41:22.244302Z","steps":["trace[702961851] 'read index received' (duration: 84.827282ms)","trace[702961851] 'applied index is now lower than readState.Index' (duration: 292.381507ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:22.244379Z","caller":"traceutil/trace.go:171","msg":"trace[1827669546] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"773.335493ms","start":"2026-05-29T05:41:21.47099Z","end":"2026-05-29T05:41:22.244326Z","steps":["trace[1827669546] 'process raft request' (duration: 480.948757ms)","trace[1827669546] 'compare' (duration: 291.940984ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:22.244672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.574745ms","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-05-29T05:41:22.24504Z","caller":"traceutil/trace.go:171","msg":"trace[2021637771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"377.996686ms","start":"2026-05-29T05:41:21.867028Z","end":"2026-05-29T05:41:22.245024Z","steps":["trace[2021637771] 'agreement among raft nodes before linearized reading' (duration: 377.412883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:22.245078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:21.867003Z","time spent":"378.064391ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:41:22.244747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.350803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nxkfq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-29T05:41:22.245164Z","caller":"traceutil/trace.go:171","msg":"trace[1933036795] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nxkfq; range_end:; response_count:1; response_revision:647; }","duration":"349.797064ms","start":"2026-05-29T05:41:21.895341Z","end":"2026-05-29T05:41:22.245138Z","steps":["trace[1933036795] 'agreement among raft nodes before linearized reading' (duration: 349.215091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:22.245232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:21.89532Z","time spent":"349.897432ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-nxkfq\" "} {"level":"warn","ts":"2026-05-29T05:41:22.244739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.594014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:41:22.245309Z","caller":"traceutil/trace.go:171","msg":"trace[1448302802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"177.230781ms","start":"2026-05-29T05:41:22.068057Z","end":"2026-05-29T05:41:22.245287Z","steps":["trace[1448302802] 'agreement among raft nodes before linearized reading' (duration: 176.570773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:22.244839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.898067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:41:22.245396Z","caller":"traceutil/trace.go:171","msg":"trace[36410005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"333.506119ms","start":"2026-05-29T05:41:21.911877Z","end":"2026-05-29T05:41:22.245383Z","steps":["trace[36410005] 'agreement among raft nodes before linearized reading' (duration: 332.753206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:22.245437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:21.911866Z","time spent":"333.564134ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:41:22.245675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:21.470967Z","time spent":"773.963859ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:41:23.161371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.159873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:41:23.161545Z","caller":"traceutil/trace.go:171","msg":"trace[1281759816] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"912.00437ms","start":"2026-05-29T05:41:22.249509Z","end":"2026-05-29T05:41:23.161514Z","steps":["trace[1281759816] 'process raft request' (duration: 124.561276ms)","trace[1281759816] 'compare' (duration: 786.979031ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:23.161633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:22.24948Z","time spent":"912.113188ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:41:23.211072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496362642771797,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:41:23.711828Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496362642771797,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:41:23.966818Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.592811716s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:41:23.967616Z","caller":"traceutil/trace.go:171","msg":"trace[1724560352] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.708749289s","start":"2026-05-29T05:41:22.25884Z","end":"2026-05-29T05:41:23.96759Z","steps":["trace[1724560352] 'process raft request' (duration: 1.708657572s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:23.967644Z","caller":"traceutil/trace.go:171","msg":"trace[992963856] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.717604529s","start":"2026-05-29T05:41:22.250019Z","end":"2026-05-29T05:41:23.967623Z","steps":["trace[992963856] 'process raft request' (duration: 1.717228132s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:23.967856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:22.258811Z","time spent":"1.70889585s","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:41:23.967991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:22.249983Z","time spent":"1.71788693s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:41:25.067806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999983072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-29T05:41:25.067919Z","caller":"traceutil/trace.go:171","msg":"trace[1846558666] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000275723s","start":"2026-05-29T05:41:23.067617Z","end":"2026-05-29T05:41:25.067892Z","steps":["trace[1846558666] 'agreement among raft nodes before linearized reading' (duration: 2.000037995s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:25.067984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:23.067594Z","time spent":"2.00037745s","remote":"127.0.0.1:44058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/29 05:41:25 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T05:41:26.694876Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.727704678s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:41:26.695482Z","caller":"traceutil/trace.go:171","msg":"trace[517755399] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:672; }","duration":"3.985439706s","start":"2026-05-29T05:41:22.710006Z","end":"2026-05-29T05:41:26.695446Z","steps":["trace[517755399] 'read index received' (duration: 1.257231271s)","trace[517755399] 'applied index is now lower than readState.Index' (duration: 2.728207254s)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:26.69561Z","caller":"traceutil/trace.go:171","msg":"trace[1726537563] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"4.076261644s","start":"2026-05-29T05:41:22.61932Z","end":"2026-05-29T05:41:26.695582Z","steps":["trace[1726537563] 'process raft request' (duration: 4.075905279s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:26.695791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.985768979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T05:41:26.695806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:22.619267Z","time spent":"4.076413485s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:41:26.695852Z","caller":"traceutil/trace.go:171","msg":"trace[1130824543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"3.985890938s","start":"2026-05-29T05:41:22.709944Z","end":"2026-05-29T05:41:26.695835Z","steps":["trace[1130824543] 'agreement among raft nodes before linearized reading' (duration: 3.985666771s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:26.695893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:22.709926Z","time spent":"3.985956933s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:41:26.695986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.771290371s","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-05-29T05:41:26.696048Z","caller":"traceutil/trace.go:171","msg":"trace[646680231] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:651; }","duration":"1.771391029s","start":"2026-05-29T05:41:24.924641Z","end":"2026-05-29T05:41:26.696032Z","steps":["trace[646680231] 'agreement among raft nodes before linearized reading' (duration: 1.771240098s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:26.696042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.252535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T05:41:26.696097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:24.924613Z","time spent":"1.771473085s","remote":"127.0.0.1:44076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"info","ts":"2026-05-29T05:41:26.696168Z","caller":"traceutil/trace.go:171","msg":"trace[386768641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"723.45128ms","start":"2026-05-29T05:41:25.972675Z","end":"2026-05-29T05:41:26.696126Z","steps":["trace[386768641] 'agreement among raft nodes before linearized reading' (duration: 723.166018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:26.696225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:25.972642Z","time spent":"723.573049ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:41:26.696217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.62760197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:41:26.696453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.5301956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:41:26.69645Z","caller":"traceutil/trace.go:171","msg":"trace[1754386011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"1.62801137s","start":"2026-05-29T05:41:25.068368Z","end":"2026-05-29T05:41:26.69638Z","steps":["trace[1754386011] 'agreement among raft nodes before linearized reading' (duration: 1.627487122s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:26.696494Z","caller":"traceutil/trace.go:171","msg":"trace[1213223818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"1.530273575s","start":"2026-05-29T05:41:25.166209Z","end":"2026-05-29T05:41:26.696482Z","steps":["trace[1213223818] 'agreement among raft nodes before linearized reading' (duration: 1.529551642s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:26.696522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:25.166182Z","time spent":"1.53033451s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:41:26.696573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:25.068329Z","time spent":"1.628228546s","remote":"127.0.0.1:44044","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-05-29T05:41:26.69671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.471980128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T05:41:26.696832Z","caller":"traceutil/trace.go:171","msg":"trace[981813952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:651; }","duration":"3.473016994s","start":"2026-05-29T05:41:23.223788Z","end":"2026-05-29T05:41:26.696805Z","steps":["trace[981813952] 'agreement among raft nodes before linearized reading' (duration: 3.471949576s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:26.696897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:23.223759Z","time spent":"3.473120291s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:41:27.204568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496362642771803,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:41:27.712961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.752864ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-29T05:41:27.713131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:26.697463Z","time spent":"1.015660662s","remote":"127.0.0.1:44076","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-29T05:41:27.969115Z","caller":"traceutil/trace.go:171","msg":"trace[1340195349] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.268011161s","start":"2026-05-29T05:41:26.701078Z","end":"2026-05-29T05:41:27.96909Z","steps":["trace[1340195349] 'process raft request' (duration: 1.267931236s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:27.96918Z","caller":"traceutil/trace.go:171","msg":"trace[1028723831] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.268208656s","start":"2026-05-29T05:41:26.700928Z","end":"2026-05-29T05:41:27.969137Z","steps":["trace[1028723831] 'process raft request' (duration: 1.267922335s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:27.96929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:26.700936Z","time spent":"1.26825512s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:41:27.969433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:26.700899Z","time spent":"1.26840194s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:41:27.969462Z","caller":"traceutil/trace.go:171","msg":"trace[1934427926] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.268233408s","start":"2026-05-29T05:41:26.701198Z","end":"2026-05-29T05:41:27.969432Z","steps":["trace[1934427926] 'process raft request' (duration: 1.267854181s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:27.969501Z","caller":"traceutil/trace.go:171","msg":"trace[2103424713] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:675; }","duration":"1.265219787s","start":"2026-05-29T05:41:26.704253Z","end":"2026-05-29T05:41:27.969473Z","steps":["trace[2103424713] 'read index received' (duration: 803.981174ms)","trace[2103424713] 'applied index is now lower than readState.Index' (duration: 461.237763ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:27.969599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:26.70119Z","time spent":"1.268333725s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:41:27.969682Z","caller":"traceutil/trace.go:171","msg":"trace[1829069377] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.268207635s","start":"2026-05-29T05:41:26.701466Z","end":"2026-05-29T05:41:27.969674Z","steps":["trace[1829069377] 'process raft request' (duration: 1.267838878s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:27.969735Z","caller":"traceutil/trace.go:171","msg":"trace[1234199454] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.268434662s","start":"2026-05-29T05:41:26.701292Z","end":"2026-05-29T05:41:27.969726Z","steps":["trace[1234199454] 'process raft request' (duration: 1.26812305s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:27.969735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:26.701458Z","time spent":"1.268251928s","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:41:27.969758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.265473326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:41:27.969779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:26.701238Z","time spent":"1.268515218s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:41:27.969795Z","caller":"traceutil/trace.go:171","msg":"trace[1186349692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"1.265551033s","start":"2026-05-29T05:41:26.704235Z","end":"2026-05-29T05:41:27.969786Z","steps":["trace[1186349692] 'agreement among raft nodes before linearized reading' (duration: 1.265341418s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:27.969829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:26.704225Z","time spent":"1.265596925s","remote":"127.0.0.1:44058","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-05-29T05:41:29.970846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998564601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-29T05:41:29.970926Z","caller":"traceutil/trace.go:171","msg":"trace[1154121609] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.998711891s","start":"2026-05-29T05:41:27.972197Z","end":"2026-05-29T05:41:29.970909Z","steps":["trace[1154121609] 'agreement among raft nodes before linearized reading' (duration: 1.998605834s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:29.970967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:27.972138Z","time spent":"1.99882187s","remote":"127.0.0.1:44044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/29 05:41:29 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T05:41:30.703831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999852814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-29T05:41:30.703972Z","caller":"traceutil/trace.go:171","msg":"trace[891262545] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00007697s","start":"2026-05-29T05:41:28.70387Z","end":"2026-05-29T05:41:30.703947Z","steps":["trace[891262545] 'agreement among raft nodes before linearized reading' (duration: 1.999901677s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:30.704167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:28.703842Z","time spent":"2.000309097s","remote":"127.0.0.1:44058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:41:31.599046Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.235933297s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-29T05:41:31.978838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999994124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-29T05:41:31.978928Z","caller":"traceutil/trace.go:171","msg":"trace[474269915] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000218071s","start":"2026-05-29T05:41:29.97869Z","end":"2026-05-29T05:41:31.978908Z","steps":["trace[474269915] 'agreement among raft nodes before linearized reading' (duration: 2.000003685s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:31.978985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:29.978657Z","time spent":"2.000312919s","remote":"127.0.0.1:44058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:41:32.04391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.94384166s","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-05-29T05:41:32.044128Z","caller":"traceutil/trace.go:171","msg":"trace[1032003336] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:656; }","duration":"1.9441237s","start":"2026-05-29T05:41:30.099974Z","end":"2026-05-29T05:41:32.044098Z","steps":["trace[1032003336] 'range keys from in-memory index tree' (duration: 1.943805927s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:32.044659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.947143155s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:41:32.045619Z","caller":"traceutil/trace.go:171","msg":"trace[1295616537] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"4.073315437s","start":"2026-05-29T05:41:27.972252Z","end":"2026-05-29T05:41:32.045567Z","steps":["trace[1295616537] 'read index received' (duration: 125.165218ms)","trace[1295616537] 'applied index is now lower than readState.Index' (duration: 3.948146419s)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:32.045636Z","caller":"traceutil/trace.go:171","msg":"trace[1422591292] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"4.33136102s","start":"2026-05-29T05:41:27.71425Z","end":"2026-05-29T05:41:32.045611Z","steps":["trace[1422591292] 'process raft request' (duration: 383.126955ms)","trace[1422591292] 'compare' (duration: 3.946450034s)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:32.045027Z","caller":"traceutil/trace.go:171","msg":"trace[181886423] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"2.682042486s","start":"2026-05-29T05:41:29.362952Z","end":"2026-05-29T05:41:32.044994Z","steps":["trace[181886423] 'process raft request' (duration: 2.681871594s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:32.045746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:27.714227Z","time spent":"4.331468979s","remote":"127.0.0.1:44076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:41:32.045756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:29.362919Z","time spent":"2.68278051s","remote":"127.0.0.1:44304","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-05-29T05:41:32.139778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.617279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:41:32.139861Z","caller":"traceutil/trace.go:171","msg":"trace[1035342925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"159.768961ms","start":"2026-05-29T05:41:31.980074Z","end":"2026-05-29T05:41:32.139842Z","steps":["trace[1035342925] 'agreement among raft nodes before linearized reading' (duration: 159.62832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:32.139898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.161740791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18b3f2d7de190841\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-05-29T05:41:32.139985Z","caller":"traceutil/trace.go:171","msg":"trace[2064990962] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18b3f2d7de190841; range_end:; response_count:1; response_revision:658; }","duration":"2.161854239s","start":"2026-05-29T05:41:29.978113Z","end":"2026-05-29T05:41:32.139968Z","steps":["trace[2064990962] 'agreement among raft nodes before linearized reading' (duration: 2.16161077s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:32.14002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:29.978106Z","time spent":"2.161906553s","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18b3f2d7de190841\" "} {"level":"warn","ts":"2026-05-29T05:41:32.140227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.161796014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:41:32.140255Z","caller":"traceutil/trace.go:171","msg":"trace[327742847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:658; }","duration":"2.161863789s","start":"2026-05-29T05:41:29.978384Z","end":"2026-05-29T05:41:32.140248Z","steps":["trace[327742847] 'agreement among raft nodes before linearized reading' (duration: 2.161784213s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:32.140277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:29.978358Z","time spent":"2.161911093s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:41:32.140287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.161839408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T05:41:32.140312Z","caller":"traceutil/trace.go:171","msg":"trace[222226524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:658; }","duration":"2.161924464s","start":"2026-05-29T05:41:29.97838Z","end":"2026-05-29T05:41:32.140305Z","steps":["trace[222226524] 'agreement among raft nodes before linearized reading' (duration: 2.161785904s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:32.140285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.162278929s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T05:41:32.140396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.161273357s","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-05-29T05:41:32.140397Z","caller":"traceutil/trace.go:171","msg":"trace[603725024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:658; }","duration":"2.162471194s","start":"2026-05-29T05:41:29.977903Z","end":"2026-05-29T05:41:32.140374Z","steps":["trace[603725024] 'agreement among raft nodes before linearized reading' (duration: 2.162106538s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:32.140422Z","caller":"traceutil/trace.go:171","msg":"trace[411963363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:658; }","duration":"2.16131702s","start":"2026-05-29T05:41:29.979099Z","end":"2026-05-29T05:41:32.140416Z","steps":["trace[411963363] 'agreement among raft nodes before linearized reading' (duration: 2.161253296s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:32.140441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:29.979089Z","time spent":"2.161347332s","remote":"127.0.0.1:44304","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-05-29T05:41:32.140334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:29.978347Z","time spent":"2.161980788s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:41:32.140453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:29.977873Z","time spent":"2.162569301s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T05:41:32.273419Z","caller":"traceutil/trace.go:171","msg":"trace[383121652] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"128.930871ms","start":"2026-05-29T05:41:32.144462Z","end":"2026-05-29T05:41:32.273392Z","steps":["trace[383121652] 'process raft request' (duration: 122.516667ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:32.273745Z","caller":"traceutil/trace.go:171","msg":"trace[1303713777] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"129.089393ms","start":"2026-05-29T05:41:32.144626Z","end":"2026-05-29T05:41:32.273715Z","steps":["trace[1303713777] 'process raft request' (duration: 128.712336ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:32.274039Z","caller":"traceutil/trace.go:171","msg":"trace[837836161] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"128.739707ms","start":"2026-05-29T05:41:32.145262Z","end":"2026-05-29T05:41:32.274002Z","steps":["trace[837836161] 'process raft request' (duration: 128.350819ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:32.274499Z","caller":"traceutil/trace.go:171","msg":"trace[1564976916] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"128.945292ms","start":"2026-05-29T05:41:32.145521Z","end":"2026-05-29T05:41:32.274467Z","steps":["trace[1564976916] 'process raft request' (duration: 128.409292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:32.274678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.14171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:41:32.27475Z","caller":"traceutil/trace.go:171","msg":"trace[1250914004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"127.28349ms","start":"2026-05-29T05:41:32.147452Z","end":"2026-05-29T05:41:32.274736Z","steps":["trace[1250914004] 'agreement among raft nodes before linearized reading' (duration: 127.029521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:32.275099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.524358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-29T05:41:32.275415Z","caller":"traceutil/trace.go:171","msg":"trace[1321467782] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:663; }","duration":"130.864883ms","start":"2026-05-29T05:41:32.144532Z","end":"2026-05-29T05:41:32.275397Z","steps":["trace[1321467782] 'agreement among raft nodes before linearized reading' (duration: 129.871321ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:32.351588Z","caller":"traceutil/trace.go:171","msg":"trace[1335170038] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"156.959506ms","start":"2026-05-29T05:41:32.194599Z","end":"2026-05-29T05:41:32.351559Z","steps":["trace[1335170038] 'process raft request' (duration: 156.87563ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:32.351606Z","caller":"traceutil/trace.go:171","msg":"trace[1328403152] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"160.927839ms","start":"2026-05-29T05:41:32.190647Z","end":"2026-05-29T05:41:32.351575Z","steps":["trace[1328403152] 'process raft request' (duration: 160.66141ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:33.060506Z","caller":"traceutil/trace.go:171","msg":"trace[2143853401] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"117.869673ms","start":"2026-05-29T05:41:32.942606Z","end":"2026-05-29T05:41:33.060475Z","steps":["trace[2143853401] 'process raft request' (duration: 21.616502ms)","trace[2143853401] 'compare' (duration: 96.109651ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:34.547503Z","caller":"traceutil/trace.go:171","msg":"trace[844431632] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"264.375673ms","start":"2026-05-29T05:41:34.283049Z","end":"2026-05-29T05:41:34.547425Z","steps":["trace[844431632] 'process raft request' (duration: 264.130415ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:34.609802Z","caller":"traceutil/trace.go:171","msg":"trace[1241922605] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:696; }","duration":"316.510169ms","start":"2026-05-29T05:41:34.293264Z","end":"2026-05-29T05:41:34.609775Z","steps":["trace[1241922605] 'read index received' (duration: 254.261126ms)","trace[1241922605] 'applied index is now lower than readState.Index' (duration: 62.247463ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:34.609957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.656611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:41:34.609953Z","caller":"traceutil/trace.go:171","msg":"trace[1667142764] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"325.539517ms","start":"2026-05-29T05:41:34.284371Z","end":"2026-05-29T05:41:34.609911Z","steps":["trace[1667142764] 'process raft request' (duration: 325.31184ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:34.610025Z","caller":"traceutil/trace.go:171","msg":"trace[1870775892] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"325.425659ms","start":"2026-05-29T05:41:34.284531Z","end":"2026-05-29T05:41:34.609957Z","steps":["trace[1870775892] 'process raft request' (duration: 325.193851ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:34.609999Z","caller":"traceutil/trace.go:171","msg":"trace[232712214] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:673; }","duration":"316.748538ms","start":"2026-05-29T05:41:34.293239Z","end":"2026-05-29T05:41:34.609988Z","steps":["trace[232712214] 'agreement among raft nodes before linearized reading' (duration: 316.64457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:34.610202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:34.284356Z","time spent":"325.70963ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:41:34.610215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:34.293227Z","time spent":"316.974525ms","remote":"127.0.0.1:44152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":5,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:41:34.6109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:34.284488Z","time spent":"325.719321ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:41:34.610389Z","caller":"traceutil/trace.go:171","msg":"trace[1641618365] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"325.795845ms","start":"2026-05-29T05:41:34.284026Z","end":"2026-05-29T05:41:34.609822Z","steps":["trace[1641618365] 'process raft request' (duration: 325.45973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:34.611329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:34.284003Z","time spent":"327.21974ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:41:35.17463Z","caller":"traceutil/trace.go:171","msg":"trace[1064027994] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"106.537373ms","start":"2026-05-29T05:41:35.068066Z","end":"2026-05-29T05:41:35.174603Z","steps":["trace[1064027994] 'read index received' (duration: 67.183898ms)","trace[1064027994] 'applied index is now lower than readState.Index' (duration: 39.352215ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:35.174677Z","caller":"traceutil/trace.go:171","msg":"trace[2072317798] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"124.906115ms","start":"2026-05-29T05:41:35.049725Z","end":"2026-05-29T05:41:35.174631Z","steps":["trace[2072317798] 'process raft request' (duration: 85.574991ms)","trace[2072317798] 'compare' (duration: 39.176922ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:35.174855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.776611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:41:35.174897Z","caller":"traceutil/trace.go:171","msg":"trace[718448953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"106.90614ms","start":"2026-05-29T05:41:35.06798Z","end":"2026-05-29T05:41:35.174886Z","steps":["trace[718448953] 'agreement among raft nodes before linearized reading' (duration: 106.731667ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:36.700354Z","caller":"traceutil/trace.go:171","msg":"trace[281088855] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"143.312466ms","start":"2026-05-29T05:41:36.556995Z","end":"2026-05-29T05:41:36.700308Z","steps":["trace[281088855] 'process raft request' (duration: 143.004684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:36.962456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.89614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:41:36.962591Z","caller":"traceutil/trace.go:171","msg":"trace[2095409908] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"256.776158ms","start":"2026-05-29T05:41:36.705789Z","end":"2026-05-29T05:41:36.962565Z","steps":["trace[2095409908] 'process raft request' (duration: 124.668633ms)","trace[2095409908] 'compare' (duration: 131.718196ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:36.964374Z","caller":"traceutil/trace.go:171","msg":"trace[94745057] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"258.01787ms","start":"2026-05-29T05:41:36.706323Z","end":"2026-05-29T05:41:36.964341Z","steps":["trace[94745057] 'process raft request' (duration: 257.780412ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:36.964589Z","caller":"traceutil/trace.go:171","msg":"trace[445736934] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"257.581907ms","start":"2026-05-29T05:41:36.706996Z","end":"2026-05-29T05:41:36.964578Z","steps":["trace[445736934] 'process raft request' (duration: 257.271074ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:36.964732Z","caller":"traceutil/trace.go:171","msg":"trace[76767915] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"217.864318ms","start":"2026-05-29T05:41:36.746837Z","end":"2026-05-29T05:41:36.964702Z","steps":["trace[76767915] 'process raft request' (duration: 217.686745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:37.221783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.909787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:41:37.221893Z","caller":"traceutil/trace.go:171","msg":"trace[551564366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"155.091751ms","start":"2026-05-29T05:41:37.066776Z","end":"2026-05-29T05:41:37.221867Z","steps":["trace[551564366] 'range keys from in-memory index tree' (duration: 154.793279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:38.402766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.083374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:41:38.402867Z","caller":"traceutil/trace.go:171","msg":"trace[1172768599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"335.274428ms","start":"2026-05-29T05:41:38.067572Z","end":"2026-05-29T05:41:38.402847Z","steps":["trace[1172768599] 'range keys from in-memory index tree' (duration: 334.993228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:38.402902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:38.067542Z","time spent":"335.352184ms","remote":"127.0.0.1:44058","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-05-29T05:41:41.46765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.15172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-29T05:41:41.46776Z","caller":"traceutil/trace.go:171","msg":"trace[1141778680] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:744; }","duration":"277.338624ms","start":"2026-05-29T05:41:41.190394Z","end":"2026-05-29T05:41:41.467733Z","steps":["trace[1141778680] 'range keys from in-memory index tree' (duration: 276.904962ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:50.159663Z","caller":"traceutil/trace.go:171","msg":"trace[1456798780] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"117.017744ms","start":"2026-05-29T05:41:50.042614Z","end":"2026-05-29T05:41:50.159631Z","steps":["trace[1456798780] 'process raft request' (duration: 116.815088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:54.113726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212365071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:41:54.113799Z","caller":"traceutil/trace.go:171","msg":"trace[728049678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:831; }","duration":"1.212454388s","start":"2026-05-29T05:41:52.901327Z","end":"2026-05-29T05:41:54.113782Z","steps":["trace[728049678] 'range keys from in-memory index tree' (duration: 1.212231071s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:54.113841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:52.9013Z","time spent":"1.212531354s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:41:54.114314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052153265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T05:41:54.114348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066206125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gqzxg\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-05-29T05:41:54.114381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056187553s","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-05-29T05:41:54.114391Z","caller":"traceutil/trace.go:171","msg":"trace[1904875861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:831; }","duration":"1.052288585s","start":"2026-05-29T05:41:53.062088Z","end":"2026-05-29T05:41:54.114377Z","steps":["trace[1904875861] 'range keys from in-memory index tree' (duration: 1.052020455s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:54.114391Z","caller":"traceutil/trace.go:171","msg":"trace[1770415600] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gqzxg; range_end:; response_count:1; response_revision:831; }","duration":"1.066289921s","start":"2026-05-29T05:41:53.048085Z","end":"2026-05-29T05:41:54.114375Z","steps":["trace[1770415600] 'range keys from in-memory index tree' (duration: 1.066102057s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:54.114423Z","caller":"traceutil/trace.go:171","msg":"trace[1558087092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:831; }","duration":"1.056239786s","start":"2026-05-29T05:41:53.058168Z","end":"2026-05-29T05:41:54.114408Z","steps":["trace[1558087092] 'range keys from in-memory index tree' (duration: 1.056114286s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:54.114426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:53.062068Z","time spent":"1.05234808s","remote":"127.0.0.1:44304","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-05-29T05:41:54.114428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:53.048064Z","time spent":"1.066354906s","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gqzxg\" "} {"level":"warn","ts":"2026-05-29T05:41:54.114448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:53.058152Z","time spent":"1.0562913s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:41:54.114448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057186688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T05:41:54.11453Z","caller":"traceutil/trace.go:171","msg":"trace[251121209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:831; }","duration":"1.057301807s","start":"2026-05-29T05:41:53.05721Z","end":"2026-05-29T05:41:54.114512Z","steps":["trace[251121209] 'range keys from in-memory index tree' (duration: 1.057001104s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:54.115649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:41:53.057199Z","time spent":"1.058433934s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T05:41:54.253945Z","caller":"traceutil/trace.go:171","msg":"trace[1627081726] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"134.599279ms","start":"2026-05-29T05:41:54.119313Z","end":"2026-05-29T05:41:54.253912Z","steps":["trace[1627081726] 'process raft request' (duration: 134.401254ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:54.253948Z","caller":"traceutil/trace.go:171","msg":"trace[1612486230] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"132.64059ms","start":"2026-05-29T05:41:54.121279Z","end":"2026-05-29T05:41:54.253919Z","steps":["trace[1612486230] 'process raft request' (duration: 132.534972ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:54.254191Z","caller":"traceutil/trace.go:171","msg":"trace[320049993] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"131.06137ms","start":"2026-05-29T05:41:54.12309Z","end":"2026-05-29T05:41:54.254151Z","steps":["trace[320049993] 'process raft request' (duration: 130.761987ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:58.438596Z","caller":"traceutil/trace.go:171","msg":"trace[1078451757] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:908; }","duration":"127.773306ms","start":"2026-05-29T05:41:58.310801Z","end":"2026-05-29T05:41:58.438575Z","steps":["trace[1078451757] 'read index received' (duration: 3.324525ms)","trace[1078451757] 'applied index is now lower than readState.Index' (duration: 124.447681ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:41:58.438821Z","caller":"traceutil/trace.go:171","msg":"trace[1736416486] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"128.826397ms","start":"2026-05-29T05:41:58.309981Z","end":"2026-05-29T05:41:58.438808Z","steps":["trace[1736416486] 'process raft request' (duration: 29.606978ms)","trace[1736416486] 'compare' (duration: 98.781755ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:58.439069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.258524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/envoy-gateway-system/envoy-gateway-78446f96c9\" ","response":"range_response_count:1 size:5114"} {"level":"info","ts":"2026-05-29T05:41:58.43911Z","caller":"traceutil/trace.go:171","msg":"trace[341189429] range","detail":"{range_begin:/registry/replicasets/envoy-gateway-system/envoy-gateway-78446f96c9; range_end:; response_count:1; response_revision:877; }","duration":"128.323969ms","start":"2026-05-29T05:41:58.310774Z","end":"2026-05-29T05:41:58.439098Z","steps":["trace[341189429] 'agreement among raft nodes before linearized reading' (duration: 128.19618ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:58.471396Z","caller":"traceutil/trace.go:171","msg":"trace[1244922631] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"157.054209ms","start":"2026-05-29T05:41:58.314319Z","end":"2026-05-29T05:41:58.471373Z","steps":["trace[1244922631] 'process raft request' (duration: 156.812101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:58.47151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.329333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:41:58.471575Z","caller":"traceutil/trace.go:171","msg":"trace[1767550751] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:878; }","duration":"127.417959ms","start":"2026-05-29T05:41:58.344139Z","end":"2026-05-29T05:41:58.471557Z","steps":["trace[1767550751] 'agreement among raft nodes before linearized reading' (duration: 127.147599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:41:58.471585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.682977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:41:58.471626Z","caller":"traceutil/trace.go:171","msg":"trace[1001122309] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:878; }","duration":"122.740291ms","start":"2026-05-29T05:41:58.348875Z","end":"2026-05-29T05:41:58.471616Z","steps":["trace[1001122309] 'agreement among raft nodes before linearized reading' (duration: 122.668575ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:41:59.145817Z","caller":"traceutil/trace.go:171","msg":"trace[401594796] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"118.280801ms","start":"2026-05-29T05:41:59.027495Z","end":"2026-05-29T05:41:59.145776Z","steps":["trace[401594796] 'process raft request' (duration: 53.324919ms)","trace[401594796] 'compare' (duration: 64.626686ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:41:59.146292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.860338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050252"} {"level":"info","ts":"2026-05-29T05:41:59.146368Z","caller":"traceutil/trace.go:171","msg":"trace[161353410] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:882; }","duration":"132.975007ms","start":"2026-05-29T05:41:59.013373Z","end":"2026-05-29T05:41:59.146348Z","steps":["trace[161353410] 'range keys from in-memory index tree' (duration: 131.793086ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:42:01.842326Z","caller":"traceutil/trace.go:171","msg":"trace[1596572311] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"235.347428ms","start":"2026-05-29T05:42:01.606958Z","end":"2026-05-29T05:42:01.842305Z","steps":["trace[1596572311] 'process raft request' (duration: 235.197837ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:42:02.635447Z","caller":"traceutil/trace.go:171","msg":"trace[1881815206] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:947; }","duration":"144.517729ms","start":"2026-05-29T05:42:02.490899Z","end":"2026-05-29T05:42:02.635416Z","steps":["trace[1881815206] 'read index received' (duration: 144.26878ms)","trace[1881815206] 'applied index is now lower than readState.Index' (duration: 247.849µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:42:02.635548Z","caller":"traceutil/trace.go:171","msg":"trace[747328821] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"241.386274ms","start":"2026-05-29T05:42:02.394117Z","end":"2026-05-29T05:42:02.635504Z","steps":["trace[747328821] 'process raft request' (duration: 241.257174ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:42:02.635481Z","caller":"traceutil/trace.go:171","msg":"trace[1920704218] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"252.287291ms","start":"2026-05-29T05:42:02.383172Z","end":"2026-05-29T05:42:02.635459Z","steps":["trace[1920704218] 'process raft request' (duration: 252.055854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:42:02.635681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.199622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:42:02.635729Z","caller":"traceutil/trace.go:171","msg":"trace[1552481739] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:915; }","duration":"142.29951ms","start":"2026-05-29T05:42:02.493415Z","end":"2026-05-29T05:42:02.635714Z","steps":["trace[1552481739] 'agreement among raft nodes before linearized reading' (duration: 142.207543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:42:02.635736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.833363ms","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-05-29T05:42:02.635815Z","caller":"traceutil/trace.go:171","msg":"trace[975857908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"144.955344ms","start":"2026-05-29T05:42:02.490845Z","end":"2026-05-29T05:42:02.6358Z","steps":["trace[975857908] 'agreement among raft nodes before linearized reading' (duration: 144.718816ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:42:04.475397Z","caller":"traceutil/trace.go:171","msg":"trace[1854041854] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"107.77443ms","start":"2026-05-29T05:42:04.367596Z","end":"2026-05-29T05:42:04.475371Z","steps":["trace[1854041854] 'read index received' (duration: 106.984319ms)","trace[1854041854] 'applied index is now lower than readState.Index' (duration: 789.321µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:42:04.475456Z","caller":"traceutil/trace.go:171","msg":"trace[616546610] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"174.02869ms","start":"2026-05-29T05:42:04.301389Z","end":"2026-05-29T05:42:04.475418Z","steps":["trace[616546610] 'process raft request' (duration: 173.206476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:42:04.475583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.975656ms","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-05-29T05:42:04.47561Z","caller":"traceutil/trace.go:171","msg":"trace[1052848814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:925; }","duration":"108.062453ms","start":"2026-05-29T05:42:04.36754Z","end":"2026-05-29T05:42:04.475602Z","steps":["trace[1052848814] 'agreement among raft nodes before linearized reading' (duration: 107.915712ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:42:06.991054Z","caller":"traceutil/trace.go:171","msg":"trace[491156636] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"317.772615ms","start":"2026-05-29T05:42:06.673242Z","end":"2026-05-29T05:42:06.991015Z","steps":["trace[491156636] 'process raft request' (duration: 317.70771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:42:06.991202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:42:06.673225Z","time spent":"317.903625ms","remote":"127.0.0.1:44304","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-05-29T05:42:06.991084Z","caller":"traceutil/trace.go:171","msg":"trace[1974544403] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"318.10398ms","start":"2026-05-29T05:42:06.672939Z","end":"2026-05-29T05:42:06.991043Z","steps":["trace[1974544403] 'process raft request' (duration: 236.69171ms)","trace[1974544403] 'compare' (duration: 81.163301ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:42:06.991716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:42:06.672927Z","time spent":"318.543315ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2492,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:42:08.451717Z","caller":"traceutil/trace.go:171","msg":"trace[1643708426] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"131.800436ms","start":"2026-05-29T05:42:08.319892Z","end":"2026-05-29T05:42:08.451692Z","steps":["trace[1643708426] 'process raft request' (duration: 131.646583ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:42:08.462539Z","caller":"traceutil/trace.go:171","msg":"trace[153954052] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"142.567259ms","start":"2026-05-29T05:42:08.319951Z","end":"2026-05-29T05:42:08.462518Z","steps":["trace[153954052] 'process raft request' (duration: 142.467402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:42:18.793081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.894058ms","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-05-29T05:42:18.793162Z","caller":"traceutil/trace.go:171","msg":"trace[1663923933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"174.015448ms","start":"2026-05-29T05:42:18.619128Z","end":"2026-05-29T05:42:18.793143Z","steps":["trace[1663923933] 'range keys from in-memory index tree' (duration: 173.670982ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:42:20.181932Z","caller":"traceutil/trace.go:171","msg":"trace[253810076] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"116.888509ms","start":"2026-05-29T05:42:20.065014Z","end":"2026-05-29T05:42:20.181903Z","steps":["trace[253810076] 'read index received' (duration: 67.644245ms)","trace[253810076] 'applied index is now lower than readState.Index' (duration: 49.242604ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:42:20.182079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.044381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:42:20.182117Z","caller":"traceutil/trace.go:171","msg":"trace[100643106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"117.167631ms","start":"2026-05-29T05:42:20.064939Z","end":"2026-05-29T05:42:20.182107Z","steps":["trace[100643106] 'agreement among raft nodes before linearized reading' (duration: 117.073484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:42:24.907831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.236944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:42:24.907945Z","caller":"traceutil/trace.go:171","msg":"trace[2124489960] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"221.580799ms","start":"2026-05-29T05:42:24.686344Z","end":"2026-05-29T05:42:24.907925Z","steps":["trace[2124489960] 'process raft request' (duration: 117.138828ms)","trace[2124489960] 'compare' (duration: 103.866396ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:42:25.233129Z","caller":"traceutil/trace.go:171","msg":"trace[1978560300] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"147.541183ms","start":"2026-05-29T05:42:25.085562Z","end":"2026-05-29T05:42:25.233103Z","steps":["trace[1978560300] 'process raft request' (duration: 117.378574ms)","trace[1978560300] 'compare' (duration: 29.752608ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:42:42.531234Z","caller":"traceutil/trace.go:171","msg":"trace[1441122271] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"195.327106ms","start":"2026-05-29T05:42:42.33588Z","end":"2026-05-29T05:42:42.531207Z","steps":["trace[1441122271] 'read index received' (duration: 195.052469ms)","trace[1441122271] 'applied index is now lower than readState.Index' (duration: 273.487µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:42:42.531303Z","caller":"traceutil/trace.go:171","msg":"trace[1788171089] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"285.313406ms","start":"2026-05-29T05:42:42.245951Z","end":"2026-05-29T05:42:42.531265Z","steps":["trace[1788171089] 'process raft request' (duration: 285.06102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:42:42.531412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.518888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:42:42.531639Z","caller":"traceutil/trace.go:171","msg":"trace[205204599] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1120; }","duration":"195.795817ms","start":"2026-05-29T05:42:42.335829Z","end":"2026-05-29T05:42:42.531625Z","steps":["trace[205204599] 'agreement among raft nodes before linearized reading' (duration: 195.528759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:42:45.054279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.570821ms","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-05-29T05:42:45.054362Z","caller":"traceutil/trace.go:171","msg":"trace[2124529917] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:1128; }","duration":"126.716631ms","start":"2026-05-29T05:42:44.927623Z","end":"2026-05-29T05:42:45.054339Z","steps":["trace[2124529917] 'range keys from in-memory index tree' (duration: 126.370376ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:42:45.50836Z","caller":"traceutil/trace.go:171","msg":"trace[1142760115] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"140.028371ms","start":"2026-05-29T05:42:45.368307Z","end":"2026-05-29T05:42:45.508335Z","steps":["trace[1142760115] 'process raft request' (duration: 139.874063ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:42:49.556038Z","caller":"traceutil/trace.go:171","msg":"trace[509284359] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1186; }","duration":"340.138863ms","start":"2026-05-29T05:42:49.21588Z","end":"2026-05-29T05:42:49.556019Z","steps":["trace[509284359] 'read index received' (duration: 339.948426ms)","trace[509284359] 'applied index is now lower than readState.Index' (duration: 189.647µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:42:49.556065Z","caller":"traceutil/trace.go:171","msg":"trace[218528481] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"371.198379ms","start":"2026-05-29T05:42:49.184851Z","end":"2026-05-29T05:42:49.556049Z","steps":["trace[218528481] 'process raft request' (duration: 371.05263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:42:49.556167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:42:49.184814Z","time spent":"371.296882ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:42:49.556308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.409453ms","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-05-29T05:42:49.556389Z","caller":"traceutil/trace.go:171","msg":"trace[830060234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1143; }","duration":"340.503857ms","start":"2026-05-29T05:42:49.215865Z","end":"2026-05-29T05:42:49.556369Z","steps":["trace[830060234] 'agreement among raft nodes before linearized reading' (duration: 340.257524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:42:49.556438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:42:49.215799Z","time spent":"340.630978ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:42:49.787989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.948375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:42:49.788254Z","caller":"traceutil/trace.go:171","msg":"trace[1789659494] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1187; }","duration":"156.828782ms","start":"2026-05-29T05:42:49.631405Z","end":"2026-05-29T05:42:49.788234Z","steps":["trace[1789659494] 'read index received' (duration: 126.331277ms)","trace[1789659494] 'applied index is now lower than readState.Index' (duration: 30.496405ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:42:49.78823Z","caller":"traceutil/trace.go:171","msg":"trace[2047814747] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"227.55149ms","start":"2026-05-29T05:42:49.560644Z","end":"2026-05-29T05:42:49.788195Z","steps":["trace[2047814747] 'process raft request' (duration: 70.304127ms)","trace[2047814747] 'compare' (duration: 156.828073ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:42:49.788433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.029068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:42:49.788487Z","caller":"traceutil/trace.go:171","msg":"trace[2140331759] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1145; }","duration":"157.117812ms","start":"2026-05-29T05:42:49.631353Z","end":"2026-05-29T05:42:49.788471Z","steps":["trace[2140331759] 'agreement among raft nodes before linearized reading' (duration: 156.937305ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:42:49.788498Z","caller":"traceutil/trace.go:171","msg":"trace[1510125248] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"227.54706ms","start":"2026-05-29T05:42:49.560917Z","end":"2026-05-29T05:42:49.788464Z","steps":["trace[1510125248] 'process raft request' (duration: 227.185395ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:42:50.775242Z","caller":"traceutil/trace.go:171","msg":"trace[515489041] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"131.723926ms","start":"2026-05-29T05:42:50.643492Z","end":"2026-05-29T05:42:50.775216Z","steps":["trace[515489041] 'read index received' (duration: 131.620384ms)","trace[515489041] 'applied index is now lower than readState.Index' (duration: 102.462µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:42:50.775288Z","caller":"traceutil/trace.go:171","msg":"trace[31095731] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"144.874516ms","start":"2026-05-29T05:42:50.630387Z","end":"2026-05-29T05:42:50.775261Z","steps":["trace[31095731] 'process raft request' (duration: 144.67737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:42:50.775369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.851788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:42:50.775408Z","caller":"traceutil/trace.go:171","msg":"trace[1802647671] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1147; }","duration":"131.939562ms","start":"2026-05-29T05:42:50.643454Z","end":"2026-05-29T05:42:50.775394Z","steps":["trace[1802647671] 'agreement among raft nodes before linearized reading' (duration: 131.854998ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:42:51.134085Z","caller":"traceutil/trace.go:171","msg":"trace[1884157500] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"103.260657ms","start":"2026-05-29T05:42:51.029376Z","end":"2026-05-29T05:42:51.132637Z","steps":["trace[1884157500] 'process raft request' (duration: 102.967207ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:42:53.256446Z","caller":"traceutil/trace.go:171","msg":"trace[1969099516] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"114.2761ms","start":"2026-05-29T05:42:53.142148Z","end":"2026-05-29T05:42:53.256424Z","steps":["trace[1969099516] 'process raft request' (duration: 114.112021ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:02.051488Z","caller":"traceutil/trace.go:171","msg":"trace[153232738] linearizableReadLoop","detail":"{readStateIndex:1230; appliedIndex:1229; }","duration":"179.445493ms","start":"2026-05-29T05:43:01.872016Z","end":"2026-05-29T05:43:02.051462Z","steps":["trace[153232738] 'read index received' (duration: 179.286042ms)","trace[153232738] 'applied index is now lower than readState.Index' (duration: 158.501µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:43:02.051537Z","caller":"traceutil/trace.go:171","msg":"trace[246442600] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"180.36034ms","start":"2026-05-29T05:43:01.871137Z","end":"2026-05-29T05:43:02.051497Z","steps":["trace[246442600] 'process raft request' (duration: 180.1761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:02.051763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.723326ms","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-05-29T05:43:02.051806Z","caller":"traceutil/trace.go:171","msg":"trace[210791282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1183; }","duration":"179.804961ms","start":"2026-05-29T05:43:01.871991Z","end":"2026-05-29T05:43:02.051796Z","steps":["trace[210791282] 'agreement among raft nodes before linearized reading' (duration: 179.606223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:02.051938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.784448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:43:02.052038Z","caller":"traceutil/trace.go:171","msg":"trace[1917318683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1183; }","duration":"120.91487ms","start":"2026-05-29T05:43:01.931093Z","end":"2026-05-29T05:43:02.052008Z","steps":["trace[1917318683] 'agreement among raft nodes before linearized reading' (duration: 120.618776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:02.26031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.561401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:43:02.260478Z","caller":"traceutil/trace.go:171","msg":"trace[1097300451] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"204.699304ms","start":"2026-05-29T05:43:02.055761Z","end":"2026-05-29T05:43:02.26046Z","steps":["trace[1097300451] 'process raft request' (duration: 204.647517ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:02.260545Z","caller":"traceutil/trace.go:171","msg":"trace[681293386] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"204.778621ms","start":"2026-05-29T05:43:02.055742Z","end":"2026-05-29T05:43:02.260521Z","steps":["trace[681293386] 'process raft request' (duration: 61.905867ms)","trace[681293386] 'compare' (duration: 142.467846ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:43:19.771155Z","caller":"traceutil/trace.go:171","msg":"trace[1771770657] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"192.05632ms","start":"2026-05-29T05:43:19.579075Z","end":"2026-05-29T05:43:19.771131Z","steps":["trace[1771770657] 'process raft request' (duration: 191.922416ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:35.479846Z","caller":"traceutil/trace.go:171","msg":"trace[1550642174] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"178.985161ms","start":"2026-05-29T05:43:35.300835Z","end":"2026-05-29T05:43:35.47982Z","steps":["trace[1550642174] 'read index received' (duration: 178.733279ms)","trace[1550642174] 'applied index is now lower than readState.Index' (duration: 250.532µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:43:35.479954Z","caller":"traceutil/trace.go:171","msg":"trace[24840709] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"210.038757ms","start":"2026-05-29T05:43:35.269884Z","end":"2026-05-29T05:43:35.479923Z","steps":["trace[24840709] 'process raft request' (duration: 209.758176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:35.480168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.269223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f5f183df-c471-4b83-8607-b2055fcfc8a9\" ","response":"range_response_count:1 size:3869"} {"level":"info","ts":"2026-05-29T05:43:35.480304Z","caller":"traceutil/trace.go:171","msg":"trace[890659858] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f5f183df-c471-4b83-8607-b2055fcfc8a9; range_end:; response_count:1; response_revision:1311; }","duration":"179.473237ms","start":"2026-05-29T05:43:35.3008Z","end":"2026-05-29T05:43:35.480273Z","steps":["trace[890659858] 'agreement among raft nodes before linearized reading' (duration: 179.166206ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:36.609351Z","caller":"traceutil/trace.go:171","msg":"trace[1608053750] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"115.00676ms","start":"2026-05-29T05:43:36.494325Z","end":"2026-05-29T05:43:36.609332Z","steps":["trace[1608053750] 'read index received' (duration: 110.421861ms)","trace[1608053750] 'applied index is now lower than readState.Index' (duration: 4.583959ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:43:36.609507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.173365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:43:36.609496Z","caller":"traceutil/trace.go:171","msg":"trace[1236225082] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"118.491519ms","start":"2026-05-29T05:43:36.490971Z","end":"2026-05-29T05:43:36.609463Z","steps":["trace[1236225082] 'process raft request' (duration: 113.765794ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:36.609538Z","caller":"traceutil/trace.go:171","msg":"trace[25261051] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1317; }","duration":"115.256852ms","start":"2026-05-29T05:43:36.494272Z","end":"2026-05-29T05:43:36.609529Z","steps":["trace[25261051] 'agreement among raft nodes before linearized reading' (duration: 115.142165ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:36.757674Z","caller":"traceutil/trace.go:171","msg":"trace[1039773298] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"113.355036ms","start":"2026-05-29T05:43:36.644283Z","end":"2026-05-29T05:43:36.757638Z","steps":["trace[1039773298] 'process raft request' (duration: 74.502441ms)","trace[1039773298] 'compare' (duration: 38.695919ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:43:39.630568Z","caller":"traceutil/trace.go:171","msg":"trace[2112209576] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"135.013458ms","start":"2026-05-29T05:43:39.49553Z","end":"2026-05-29T05:43:39.630544Z","steps":["trace[2112209576] 'process raft request' (duration: 134.876292ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:39.663986Z","caller":"traceutil/trace.go:171","msg":"trace[876962359] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"165.50831ms","start":"2026-05-29T05:43:39.498453Z","end":"2026-05-29T05:43:39.663962Z","steps":["trace[876962359] 'process raft request' (duration: 165.276286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:44.075534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.780961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:43:44.075644Z","caller":"traceutil/trace.go:171","msg":"trace[564136658] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"168.508595ms","start":"2026-05-29T05:43:43.90712Z","end":"2026-05-29T05:43:44.075628Z","steps":["trace[564136658] 'read index received' (duration: 5.471361ms)","trace[564136658] 'applied index is now lower than readState.Index' (duration: 163.035954ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:43:44.07581Z","caller":"traceutil/trace.go:171","msg":"trace[1902145015] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"432.565533ms","start":"2026-05-29T05:43:43.643233Z","end":"2026-05-29T05:43:44.075798Z","steps":["trace[1902145015] 'process raft request' (duration: 269.421941ms)","trace[1902145015] 'compare' (duration: 162.592645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:43:44.075878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:43:43.643212Z","time spent":"432.632912ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:43:44.076126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.990814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:43:44.076204Z","caller":"traceutil/trace.go:171","msg":"trace[699834715] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1353; }","duration":"169.128191ms","start":"2026-05-29T05:43:43.907057Z","end":"2026-05-29T05:43:44.076186Z","steps":["trace[699834715] 'agreement among raft nodes before linearized reading' (duration: 168.915036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:44.670283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.784642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:43:44.670507Z","caller":"traceutil/trace.go:171","msg":"trace[517880452] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"678.972581ms","start":"2026-05-29T05:43:43.991481Z","end":"2026-05-29T05:43:44.670453Z","steps":["trace[517880452] 'process raft request' (duration: 399.903266ms)","trace[517880452] 'compare' (duration: 278.616446ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:43:44.670655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:43:43.991439Z","time spent":"679.146747ms","remote":"127.0.0.1:44124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:43:44.670593Z","caller":"traceutil/trace.go:171","msg":"trace[1811196783] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"594.896094ms","start":"2026-05-29T05:43:44.07567Z","end":"2026-05-29T05:43:44.670566Z","steps":["trace[1811196783] 'read index received' (duration: 315.738382ms)","trace[1811196783] 'applied index is now lower than readState.Index' (duration: 279.154432ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:43:44.671082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.437143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:43:44.671207Z","caller":"traceutil/trace.go:171","msg":"trace[1862422403] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1354; }","duration":"673.609309ms","start":"2026-05-29T05:43:43.997574Z","end":"2026-05-29T05:43:44.671183Z","steps":["trace[1862422403] 'agreement among raft nodes before linearized reading' (duration: 673.178488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:44.671273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:43:43.99756Z","time spent":"673.694847ms","remote":"127.0.0.1:58526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:43:44.723703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.835639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T05:43:44.723757Z","caller":"traceutil/trace.go:171","msg":"trace[352599654] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"643.125003ms","start":"2026-05-29T05:43:44.080602Z","end":"2026-05-29T05:43:44.723727Z","steps":["trace[352599654] 'process raft request' (duration: 642.714203ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:44.723788Z","caller":"traceutil/trace.go:171","msg":"trace[1129779251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1356; }","duration":"646.958195ms","start":"2026-05-29T05:43:44.076813Z","end":"2026-05-29T05:43:44.723771Z","steps":["trace[1129779251] 'agreement among raft nodes before linearized reading' (duration: 646.73732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:44.723808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.699346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"warn","ts":"2026-05-29T05:43:44.723842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:43:44.076794Z","time spent":"647.038544ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:43:44.723837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.808245ms","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-05-29T05:43:44.723736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.96082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T05:43:44.723902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:43:44.080585Z","time spent":"643.231901ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:43:44.724008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.078097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-05-29T05:43:44.724049Z","caller":"traceutil/trace.go:171","msg":"trace[220896424] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1356; }","duration":"278.142836ms","start":"2026-05-29T05:43:44.445896Z","end":"2026-05-29T05:43:44.724039Z","steps":["trace[220896424] 'agreement among raft nodes before linearized reading' (duration: 278.051758ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:44.724034Z","caller":"traceutil/trace.go:171","msg":"trace[124103248] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"410.889919ms","start":"2026-05-29T05:43:44.313127Z","end":"2026-05-29T05:43:44.724017Z","steps":["trace[124103248] 'process raft request' (duration: 410.359942ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:44.72391Z","caller":"traceutil/trace.go:171","msg":"trace[36660896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1356; }","duration":"326.944682ms","start":"2026-05-29T05:43:44.396946Z","end":"2026-05-29T05:43:44.723891Z","steps":["trace[36660896] 'agreement among raft nodes before linearized reading' (duration: 326.717658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:44.724195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:43:44.396935Z","time spent":"327.247946ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:43:44.724221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:43:44.313103Z","time spent":"411.007438ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:43:44.723883Z","caller":"traceutil/trace.go:171","msg":"trace[1003202713] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1356; }","duration":"287.807493ms","start":"2026-05-29T05:43:44.436057Z","end":"2026-05-29T05:43:44.723865Z","steps":["trace[1003202713] 'agreement among raft nodes before linearized reading' (duration: 287.540149ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:44.724453Z","caller":"traceutil/trace.go:171","msg":"trace[268942536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1356; }","duration":"126.268203ms","start":"2026-05-29T05:43:44.597728Z","end":"2026-05-29T05:43:44.723996Z","steps":["trace[268942536] 'agreement among raft nodes before linearized reading' (duration: 125.841523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:45.087651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.266919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:43:45.087762Z","caller":"traceutil/trace.go:171","msg":"trace[589684761] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"356.627578ms","start":"2026-05-29T05:43:44.731114Z","end":"2026-05-29T05:43:45.087742Z","steps":["trace[589684761] 'process raft request' (duration: 120.177643ms)","trace[589684761] 'compare' (duration: 236.134202ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:43:45.087825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:43:44.731101Z","time spent":"356.695057ms","remote":"127.0.0.1:44304","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-05-29T05:43:45.111105Z","caller":"traceutil/trace.go:171","msg":"trace[817681119] linearizableReadLoop","detail":"{readStateIndex:1416; appliedIndex:1414; }","duration":"341.861054ms","start":"2026-05-29T05:43:44.769218Z","end":"2026-05-29T05:43:45.111079Z","steps":["trace[817681119] 'read index received' (duration: 82.093009ms)","trace[817681119] 'applied index is now lower than readState.Index' (duration: 259.767075ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:43:45.111301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.781071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:43:45.111444Z","caller":"traceutil/trace.go:171","msg":"trace[1264763285] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1360; }","duration":"326.966987ms","start":"2026-05-29T05:43:44.784455Z","end":"2026-05-29T05:43:45.111422Z","steps":["trace[1264763285] 'agreement among raft nodes before linearized reading' (duration: 326.772501ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:45.11126Z","caller":"traceutil/trace.go:171","msg":"trace[1124816146] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"378.715675ms","start":"2026-05-29T05:43:44.732462Z","end":"2026-05-29T05:43:45.111178Z","steps":["trace[1124816146] 'process raft request' (duration: 378.492061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:45.111529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:43:44.784439Z","time spent":"327.068375ms","remote":"127.0.0.1:58300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:43:45.111603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.916803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-29T05:43:45.111621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.377522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:43:45.111706Z","caller":"traceutil/trace.go:171","msg":"trace[2108948744] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:1360; }","duration":"180.065429ms","start":"2026-05-29T05:43:44.931619Z","end":"2026-05-29T05:43:45.111684Z","steps":["trace[2108948744] 'agreement among raft nodes before linearized reading' (duration: 179.795475ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:45.111706Z","caller":"traceutil/trace.go:171","msg":"trace[981528787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1360; }","duration":"342.531939ms","start":"2026-05-29T05:43:44.769159Z","end":"2026-05-29T05:43:45.111691Z","steps":["trace[981528787] 'agreement among raft nodes before linearized reading' (duration: 342.04983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:45.111979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:43:44.769134Z","time spent":"342.832872ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:43:45.112054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:43:44.732443Z","time spent":"379.111918ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:43:47.129677Z","caller":"traceutil/trace.go:171","msg":"trace[462489836] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"182.316208ms","start":"2026-05-29T05:43:46.947333Z","end":"2026-05-29T05:43:47.129649Z","steps":["trace[462489836] 'process raft request' (duration: 180.83054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:47.843573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.121349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:43:47.843689Z","caller":"traceutil/trace.go:171","msg":"trace[1978982009] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1426; }","duration":"546.327357ms","start":"2026-05-29T05:43:47.297344Z","end":"2026-05-29T05:43:47.843671Z","steps":["trace[1978982009] 'read index received' (duration: 42.04µs)","trace[1978982009] 'applied index is now lower than readState.Index' (duration: 546.283527ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:43:47.843785Z","caller":"traceutil/trace.go:171","msg":"trace[786376131] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"636.22547ms","start":"2026-05-29T05:43:47.207548Z","end":"2026-05-29T05:43:47.843773Z","steps":["trace[786376131] 'process raft request' (duration: 57.799428ms)","trace[786376131] 'compare' (duration: 577.870813ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:43:47.843847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:43:47.207507Z","time spent":"636.310098ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:43:47.843922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.590053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-29T05:43:47.844005Z","caller":"traceutil/trace.go:171","msg":"trace[1365542292] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1370; }","duration":"167.670751ms","start":"2026-05-29T05:43:47.676307Z","end":"2026-05-29T05:43:47.843978Z","steps":["trace[1365542292] 'agreement among raft nodes before linearized reading' (duration: 167.486535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:47.844164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.806226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:43:47.844237Z","caller":"traceutil/trace.go:171","msg":"trace[1703283358] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1370; }","duration":"546.926363ms","start":"2026-05-29T05:43:47.297293Z","end":"2026-05-29T05:43:47.84422Z","steps":["trace[1703283358] 'agreement among raft nodes before linearized reading' (duration: 546.822385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:43:47.844281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:43:47.297246Z","time spent":"547.024281ms","remote":"127.0.0.1:44208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:43:47.844351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.921762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:43:47.84439Z","caller":"traceutil/trace.go:171","msg":"trace[752698067] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1370; }","duration":"110.991551ms","start":"2026-05-29T05:43:47.733388Z","end":"2026-05-29T05:43:47.84438Z","steps":["trace[752698067] 'agreement among raft nodes before linearized reading' (duration: 110.929712ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:48.903876Z","caller":"traceutil/trace.go:171","msg":"trace[1470567102] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"142.467606ms","start":"2026-05-29T05:43:48.761311Z","end":"2026-05-29T05:43:48.903779Z","steps":["trace[1470567102] 'process raft request' (duration: 142.27065ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:43:49.112523Z","caller":"traceutil/trace.go:171","msg":"trace[1035687177] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"203.784014ms","start":"2026-05-29T05:43:48.908711Z","end":"2026-05-29T05:43:49.112495Z","steps":["trace[1035687177] 'process raft request' (duration: 203.475751ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:44:03.117113Z","caller":"traceutil/trace.go:171","msg":"trace[1587560778] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"102.234877ms","start":"2026-05-29T05:44:03.014839Z","end":"2026-05-29T05:44:03.117074Z","steps":["trace[1587560778] 'process raft request' (duration: 102.039009ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:44:04.0506Z","caller":"traceutil/trace.go:171","msg":"trace[1538811138] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"103.911728ms","start":"2026-05-29T05:44:03.946651Z","end":"2026-05-29T05:44:04.050563Z","steps":["trace[1538811138] 'process raft request' (duration: 103.430323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:44:04.242142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.322913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-account-controller\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-05-29T05:44:04.24221Z","caller":"traceutil/trace.go:171","msg":"trace[369665057] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-account-controller; range_end:; response_count:1; response_revision:1426; }","duration":"112.414583ms","start":"2026-05-29T05:44:04.12978Z","end":"2026-05-29T05:44:04.242195Z","steps":["trace[369665057] 'range keys from in-memory index tree' (duration: 112.178886ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:44:05.556385Z","caller":"traceutil/trace.go:171","msg":"trace[901850367] linearizableReadLoop","detail":"{readStateIndex:1493; appliedIndex:1492; }","duration":"266.226805ms","start":"2026-05-29T05:44:05.290128Z","end":"2026-05-29T05:44:05.556354Z","steps":["trace[901850367] 'read index received' (duration: 209.228528ms)","trace[901850367] 'applied index is now lower than readState.Index' (duration: 56.996237ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:44:05.55673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.246318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-05-29T05:44:05.556726Z","caller":"traceutil/trace.go:171","msg":"trace[98346142] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"311.08571ms","start":"2026-05-29T05:44:05.245459Z","end":"2026-05-29T05:44:05.556544Z","steps":["trace[98346142] 'process raft request' (duration: 253.837535ms)","trace[98346142] 'compare' (duration: 56.806239ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:44:05.556788Z","caller":"traceutil/trace.go:171","msg":"trace[757640633] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:1; response_revision:1432; }","duration":"346.354987ms","start":"2026-05-29T05:44:05.21042Z","end":"2026-05-29T05:44:05.556775Z","steps":["trace[757640633] 'agreement among raft nodes before linearized reading' (duration: 346.225439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:44:05.556822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:44:05.210405Z","time spent":"346.409086ms","remote":"127.0.0.1:44386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":717,"request content":"key:\"/registry/clusterrolebindings/system:discovery\" "} {"level":"warn","ts":"2026-05-29T05:44:05.556883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:44:05.245429Z","time spent":"311.363366ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:44:05.557238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.517534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:44:05.557535Z","caller":"traceutil/trace.go:171","msg":"trace[683108802] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1432; }","duration":"206.900052ms","start":"2026-05-29T05:44:05.350614Z","end":"2026-05-29T05:44:05.557514Z","steps":["trace[683108802] 'agreement among raft nodes before linearized reading' (duration: 206.045419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:44:05.557377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.936743ms","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-05-29T05:44:05.557991Z","caller":"traceutil/trace.go:171","msg":"trace[1839162214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1432; }","duration":"214.607246ms","start":"2026-05-29T05:44:05.343365Z","end":"2026-05-29T05:44:05.557972Z","steps":["trace[1839162214] 'agreement among raft nodes before linearized reading' (duration: 213.560556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:44:05.557653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.370493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:coredns\" ","response":"range_response_count:1 size:415"} {"level":"info","ts":"2026-05-29T05:44:05.558176Z","caller":"traceutil/trace.go:171","msg":"trace[1266698724] range","detail":"{range_begin:/registry/clusterrolebindings/system:coredns; range_end:; response_count:1; response_revision:1432; }","duration":"263.928167ms","start":"2026-05-29T05:44:05.294221Z","end":"2026-05-29T05:44:05.558149Z","steps":["trace[1266698724] 'agreement among raft nodes before linearized reading' (duration: 262.360322ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:44:09.630605Z","caller":"traceutil/trace.go:171","msg":"trace[1653842011] linearizableReadLoop","detail":"{readStateIndex:1505; appliedIndex:1504; }","duration":"223.033108ms","start":"2026-05-29T05:44:09.407551Z","end":"2026-05-29T05:44:09.630584Z","steps":["trace[1653842011] 'read index received' (duration: 222.79325ms)","trace[1653842011] 'applied index is now lower than readState.Index' (duration: 238.938µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:44:09.630697Z","caller":"traceutil/trace.go:171","msg":"trace[1892520218] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"228.05432ms","start":"2026-05-29T05:44:09.402615Z","end":"2026-05-29T05:44:09.63067Z","steps":["trace[1892520218] 'process raft request' (duration: 227.829802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:44:09.630866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.303206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding\" ","response":"range_response_count:1 size:1146"} {"level":"warn","ts":"2026-05-29T05:44:09.630893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.172887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:912"} {"level":"info","ts":"2026-05-29T05:44:09.630975Z","caller":"traceutil/trace.go:171","msg":"trace[1322789769] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1444; }","duration":"208.282427ms","start":"2026-05-29T05:44:09.422673Z","end":"2026-05-29T05:44:09.630955Z","steps":["trace[1322789769] 'agreement among raft nodes before linearized reading' (duration: 208.077088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:44:09.631037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.942954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-05-29T05:44:09.63108Z","caller":"traceutil/trace.go:171","msg":"trace[2003782487] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:1444; }","duration":"159.988434ms","start":"2026-05-29T05:44:09.471082Z","end":"2026-05-29T05:44:09.63107Z","steps":["trace[2003782487] 'agreement among raft nodes before linearized reading' (duration: 159.871255ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:44:09.630923Z","caller":"traceutil/trace.go:171","msg":"trace[956327273] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding; range_end:; response_count:1; response_revision:1444; }","duration":"223.415185ms","start":"2026-05-29T05:44:09.407496Z","end":"2026-05-29T05:44:09.630911Z","steps":["trace[956327273] 'agreement among raft nodes before linearized reading' (duration: 223.229486ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:44:12.460025Z","caller":"traceutil/trace.go:171","msg":"trace[1030262665] linearizableReadLoop","detail":"{readStateIndex:1516; appliedIndex:1515; }","duration":"117.344047ms","start":"2026-05-29T05:44:12.342668Z","end":"2026-05-29T05:44:12.460012Z","steps":["trace[1030262665] 'read index received' (duration: 117.252367ms)","trace[1030262665] 'applied index is now lower than readState.Index' (duration: 91.05µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:44:12.460049Z","caller":"traceutil/trace.go:171","msg":"trace[156852450] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"117.749495ms","start":"2026-05-29T05:44:12.342284Z","end":"2026-05-29T05:44:12.460034Z","steps":["trace[156852450] 'process raft request' (duration: 117.629705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:44:12.460151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.469556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-proxy\" ","response":"range_response_count:1 size:1949"} {"level":"info","ts":"2026-05-29T05:44:12.460175Z","caller":"traceutil/trace.go:171","msg":"trace[1488573118] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1454; }","duration":"117.538106ms","start":"2026-05-29T05:44:12.342629Z","end":"2026-05-29T05:44:12.460168Z","steps":["trace[1488573118] 'agreement among raft nodes before linearized reading' (duration: 117.481086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:44:12.460227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.114749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"warn","ts":"2026-05-29T05:44:12.460241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.350322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/local-path-storage/\" range_end:\"/registry/roles/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:44:12.460288Z","caller":"traceutil/trace.go:171","msg":"trace[1459369679] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1454; }","duration":"117.212808ms","start":"2026-05-29T05:44:12.343061Z","end":"2026-05-29T05:44:12.460274Z","steps":["trace[1459369679] 'agreement among raft nodes before linearized reading' (duration: 117.067269ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:44:12.460316Z","caller":"traceutil/trace.go:171","msg":"trace[642402549] range","detail":"{range_begin:/registry/roles/local-path-storage/; range_end:/registry/roles/local-path-storage0; response_count:0; response_revision:1454; }","duration":"104.479641ms","start":"2026-05-29T05:44:12.355823Z","end":"2026-05-29T05:44:12.460303Z","steps":["trace[642402549] 'agreement among raft nodes before linearized reading' (duration: 104.339812ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:44:12.996414Z","caller":"traceutil/trace.go:171","msg":"trace[1340478502] linearizableReadLoop","detail":"{readStateIndex:1517; appliedIndex:1516; }","duration":"124.496726ms","start":"2026-05-29T05:44:12.871895Z","end":"2026-05-29T05:44:12.996392Z","steps":["trace[1340478502] 'read index received' (duration: 124.341747ms)","trace[1340478502] 'applied index is now lower than readState.Index' (duration: 154.129µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:44:12.996531Z","caller":"traceutil/trace.go:171","msg":"trace[272593201] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"139.829498ms","start":"2026-05-29T05:44:12.856668Z","end":"2026-05-29T05:44:12.996497Z","steps":["trace[272593201] 'process raft request' (duration: 139.54586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:44:12.996619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.716204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14480"} {"level":"info","ts":"2026-05-29T05:44:12.996659Z","caller":"traceutil/trace.go:171","msg":"trace[964630676] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1455; }","duration":"124.795004ms","start":"2026-05-29T05:44:12.871855Z","end":"2026-05-29T05:44:12.99665Z","steps":["trace[964630676] 'agreement among raft nodes before linearized reading' (duration: 124.643535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:44:12.996679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.513191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"info","ts":"2026-05-29T05:44:12.996763Z","caller":"traceutil/trace.go:171","msg":"trace[1077882518] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1455; }","duration":"109.63924ms","start":"2026-05-29T05:44:12.887105Z","end":"2026-05-29T05:44:12.996744Z","steps":["trace[1077882518] 'agreement among raft nodes before linearized reading' (duration: 109.450262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:44:24.858236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.391699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:44:24.858714Z","caller":"traceutil/trace.go:171","msg":"trace[1647723125] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1494; }","duration":"185.913619ms","start":"2026-05-29T05:44:24.672775Z","end":"2026-05-29T05:44:24.858688Z","steps":["trace[1647723125] 'count revisions from in-memory index tree' (duration: 185.300398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:44:24.858303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.970903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nxkfq\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-05-29T05:44:24.859266Z","caller":"traceutil/trace.go:171","msg":"trace[1290170153] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nxkfq; range_end:; response_count:1; response_revision:1494; }","duration":"227.969224ms","start":"2026-05-29T05:44:24.63128Z","end":"2026-05-29T05:44:24.859249Z","steps":["trace[1290170153] 'range keys from in-memory index tree' (duration: 226.814633ms)"],"step_count":1}