{"level":"warn","ts":"2026-04-17T18:38:14.977614Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T18:38:14.977705Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.82: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.19.213.82:2380","--initial-cluster=instance=https://199.19.213.82:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.82:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.82: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-04-17T18:38:14.977778Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T18:38:14.977792Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.82:2380"]} {"level":"info","ts":"2026-04-17T18:38:14.977812Z","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-04-17T18:38:14.978464Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.82:2379"]} {"level":"info","ts":"2026-04-17T18:38:14.97855Z","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.19.213.82:2380"],"listen-peer-urls":["https://199.19.213.82:2380"],"advertise-client-urls":["https://199.19.213.82:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.82:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.82: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-04-17T18:38:14.9857Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.927067ms"} {"level":"info","ts":"2026-04-17T18:38:15.017129Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5d464d3ed167a141","cluster-id":"2ab4f6fe2538e5aa"} {"level":"info","ts":"2026-04-17T18:38:15.017276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T18:38:15.017305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became follower at term 0"} {"level":"info","ts":"2026-04-17T18:38:15.017323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5d464d3ed167a141 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T18:38:15.017333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became follower at term 1"} {"level":"info","ts":"2026-04-17T18:38:15.017384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 switched to configuration voters=(6721144426093584705)"} {"level":"warn","ts":"2026-04-17T18:38:15.072276Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T18:38:15.089055Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T18:38:15.090958Z","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-04-17T18:38:15.095345Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5d464d3ed167a141","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T18:38:15.09558Z","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-04-17T18:38:15.095682Z","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-04-17T18:38:15.0966Z","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-04-17T18:38:15.096854Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5d464d3ed167a141","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T18:38:15.099453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 switched to configuration voters=(6721144426093584705)"} {"level":"info","ts":"2026-04-17T18:38:15.099718Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ab4f6fe2538e5aa","local-member-id":"5d464d3ed167a141","added-peer-id":"5d464d3ed167a141","added-peer-peer-urls":["https://199.19.213.82:2380"]} {"level":"info","ts":"2026-04-17T18:38:15.101532Z","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-04-17T18:38:15.101692Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.82:2380"} {"level":"info","ts":"2026-04-17T18:38:15.101746Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.82:2380"} {"level":"info","ts":"2026-04-17T18:38:15.101878Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5d464d3ed167a141","initial-advertise-peer-urls":["https://199.19.213.82:2380"],"listen-peer-urls":["https://199.19.213.82:2380"],"advertise-client-urls":["https://199.19.213.82:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.82:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T18:38:15.101914Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T18:38:15.618498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T18:38:15.618564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T18:38:15.618612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 received MsgPreVoteResp from 5d464d3ed167a141 at term 1"} {"level":"info","ts":"2026-04-17T18:38:15.618631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became candidate at term 2"} {"level":"info","ts":"2026-04-17T18:38:15.618641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 received MsgVoteResp from 5d464d3ed167a141 at term 2"} {"level":"info","ts":"2026-04-17T18:38:15.618662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became leader at term 2"} {"level":"info","ts":"2026-04-17T18:38:15.61868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5d464d3ed167a141 elected leader 5d464d3ed167a141 at term 2"} {"level":"info","ts":"2026-04-17T18:38:15.623678Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:38:15.625918Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5d464d3ed167a141","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.82:2379]}","request-path":"/0/members/5d464d3ed167a141/attributes","cluster-id":"2ab4f6fe2538e5aa","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T18:38:15.626043Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:38:15.627617Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.82:2379"} {"level":"info","ts":"2026-04-17T18:38:15.627849Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:38:15.629286Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T18:38:15.632338Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T18:38:15.632446Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T18:38:15.637597Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ab4f6fe2538e5aa","local-member-id":"5d464d3ed167a141","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:38:15.637731Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:38:15.637764Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-17T18:38:17.335063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.105387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-17T18:38:17.335237Z","caller":"traceutil/trace.go:171","msg":"trace[656507776] linearizableReadLoop","detail":"{readStateIndex:7; appliedIndex:6; }","duration":"245.453756ms","start":"2026-04-17T18:38:17.08976Z","end":"2026-04-17T18:38:17.335214Z","steps":["trace[656507776] 'read index received' (duration: 120.431633ms)","trace[656507776] 'applied index is now lower than readState.Index' (duration: 125.020423ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:17.335308Z","caller":"traceutil/trace.go:171","msg":"trace[1865924427] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"247.302368ms","start":"2026-04-17T18:38:17.087946Z","end":"2026-04-17T18:38:17.335248Z","steps":["trace[1865924427] 'process raft request' (duration: 122.214663ms)","trace[1865924427] 'compare' (duration: 123.874771ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:38:17.335556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.696722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:17.335678Z","caller":"traceutil/trace.go:171","msg":"trace[536203812] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:4; }","duration":"245.92727ms","start":"2026-04-17T18:38:17.0897Z","end":"2026-04-17T18:38:17.335628Z","steps":["trace[536203812] 'agreement among raft nodes before linearized reading' (duration: 245.612001ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.418304Z","caller":"traceutil/trace.go:171","msg":"trace[1465888210] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"326.066423ms","start":"2026-04-17T18:38:17.092194Z","end":"2026-04-17T18:38:17.418261Z","steps":["trace[1465888210] 'process raft request' (duration: 325.810767ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.418333Z","caller":"traceutil/trace.go:171","msg":"trace[1052429311] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"324.715735ms","start":"2026-04-17T18:38:17.093588Z","end":"2026-04-17T18:38:17.418304Z","steps":["trace[1052429311] 'process raft request' (duration: 324.583181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:17.418512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:17.093547Z","time spent":"324.87214ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:17.418541Z","caller":"traceutil/trace.go:171","msg":"trace[1453934702] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"324.749827ms","start":"2026-04-17T18:38:17.093771Z","end":"2026-04-17T18:38:17.418521Z","steps":["trace[1453934702] 'process raft request' (duration: 324.539101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:17.418529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.422271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T18:38:17.418586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:17.093183Z","time spent":"325.383654ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:17.418702Z","caller":"traceutil/trace.go:171","msg":"trace[134289043] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"324.977042ms","start":"2026-04-17T18:38:17.093708Z","end":"2026-04-17T18:38:17.418685Z","steps":["trace[134289043] 'process raft request' (duration: 324.52884ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.418658Z","caller":"traceutil/trace.go:171","msg":"trace[1782797757] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:9; }","duration":"304.628238ms","start":"2026-04-17T18:38:17.114003Z","end":"2026-04-17T18:38:17.418631Z","steps":["trace[1782797757] 'agreement among raft nodes before linearized reading' (duration: 304.36383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:17.418784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:17.093698Z","time spent":"325.049525ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:17.418761Z","caller":"traceutil/trace.go:171","msg":"trace[445234565] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"325.012475ms","start":"2026-04-17T18:38:17.093729Z","end":"2026-04-17T18:38:17.418742Z","steps":["trace[445234565] 'process raft request' (duration: 324.541131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:17.418944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.967139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T18:38:17.418991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:17.09372Z","time spent":"325.147238ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:17.419097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.437016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T18:38:17.419017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:17.113978Z","time spent":"304.807242ms","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-17T18:38:17.419158Z","caller":"traceutil/trace.go:171","msg":"trace[1866482677] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"279.521238ms","start":"2026-04-17T18:38:17.139621Z","end":"2026-04-17T18:38:17.419142Z","steps":["trace[1866482677] 'agreement among raft nodes before linearized reading' (duration: 279.438717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:17.41896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.348937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:17.419462Z","caller":"traceutil/trace.go:171","msg":"trace[1245494993] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:9; }","duration":"267.093358ms","start":"2026-04-17T18:38:17.152346Z","end":"2026-04-17T18:38:17.419406Z","steps":["trace[1245494993] 'agreement among raft nodes before linearized reading' (duration: 266.335926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:17.418457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:17.092172Z","time spent":"326.208388ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:17.421134Z","caller":"traceutil/trace.go:171","msg":"trace[905702541] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:9; }","duration":"155.038112ms","start":"2026-04-17T18:38:17.263932Z","end":"2026-04-17T18:38:17.41897Z","steps":["trace[905702541] 'agreement among raft nodes before linearized reading' (duration: 154.97371ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.579749Z","caller":"traceutil/trace.go:171","msg":"trace[1837778294] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:12; }","duration":"157.082299ms","start":"2026-04-17T18:38:17.422638Z","end":"2026-04-17T18:38:17.57972Z","steps":["trace[1837778294] 'read index received' (duration: 104.201414ms)","trace[1837778294] 'applied index is now lower than readState.Index' (duration: 52.879694ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:17.579863Z","caller":"traceutil/trace.go:171","msg":"trace[2078748023] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"241.499283ms","start":"2026-04-17T18:38:17.338317Z","end":"2026-04-17T18:38:17.579817Z","steps":["trace[2078748023] 'process raft request' (duration: 188.513016ms)","trace[2078748023] 'compare' (duration: 52.626237ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:17.579937Z","caller":"traceutil/trace.go:171","msg":"trace[1206772096] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"240.091784ms","start":"2026-04-17T18:38:17.339815Z","end":"2026-04-17T18:38:17.579907Z","steps":["trace[1206772096] 'process raft request' (duration: 239.830117ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.580031Z","caller":"traceutil/trace.go:171","msg":"trace[1019842290] transaction","detail":"{read_only:false; number_of_response:0; response_revision:10; }","duration":"241.611236ms","start":"2026-04-17T18:38:17.338391Z","end":"2026-04-17T18:38:17.580002Z","steps":["trace[1019842290] 'process raft request' (duration: 241.212645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:17.581116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.441897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:17.581225Z","caller":"traceutil/trace.go:171","msg":"trace[624579236] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:11; }","duration":"158.58107ms","start":"2026-04-17T18:38:17.422629Z","end":"2026-04-17T18:38:17.58121Z","steps":["trace[624579236] 'agreement among raft nodes before linearized reading' (duration: 157.394857ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.592748Z","caller":"traceutil/trace.go:171","msg":"trace[1884832351] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"169.950452ms","start":"2026-04-17T18:38:17.422773Z","end":"2026-04-17T18:38:17.592723Z","steps":["trace[1884832351] 'process raft request' (duration: 169.784208ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.592783Z","caller":"traceutil/trace.go:171","msg":"trace[298542214] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"169.223213ms","start":"2026-04-17T18:38:17.423538Z","end":"2026-04-17T18:38:17.592761Z","steps":["trace[298542214] 'process raft request' (duration: 169.11106ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.59306Z","caller":"traceutil/trace.go:171","msg":"trace[1554656796] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"169.082577ms","start":"2026-04-17T18:38:17.423968Z","end":"2026-04-17T18:38:17.593051Z","steps":["trace[1554656796] 'process raft request' (duration: 168.743528ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.593137Z","caller":"traceutil/trace.go:171","msg":"trace[437908903] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"169.573952ms","start":"2026-04-17T18:38:17.423554Z","end":"2026-04-17T18:38:17.593128Z","steps":["trace[437908903] 'process raft request' (duration: 169.122479ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.593217Z","caller":"traceutil/trace.go:171","msg":"trace[736922538] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"169.303864ms","start":"2026-04-17T18:38:17.423907Z","end":"2026-04-17T18:38:17.593211Z","steps":["trace[736922538] 'process raft request' (duration: 168.78621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:17.593408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.508032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T18:38:17.593425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.19401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-17T18:38:17.593439Z","caller":"traceutil/trace.go:171","msg":"trace[829656278] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:16; }","duration":"116.581585ms","start":"2026-04-17T18:38:17.47685Z","end":"2026-04-17T18:38:17.593431Z","steps":["trace[829656278] 'agreement among raft nodes before linearized reading' (duration: 116.483072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:17.593424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.511832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-17T18:38:17.593464Z","caller":"traceutil/trace.go:171","msg":"trace[553214758] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:16; }","duration":"112.275972ms","start":"2026-04-17T18:38:17.481179Z","end":"2026-04-17T18:38:17.593455Z","steps":["trace[553214758] 'agreement among raft nodes before linearized reading' (duration: 112.143718ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.593473Z","caller":"traceutil/trace.go:171","msg":"trace[1001825302] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:16; }","duration":"116.612935ms","start":"2026-04-17T18:38:17.476848Z","end":"2026-04-17T18:38:17.593461Z","steps":["trace[1001825302] 'agreement among raft nodes before linearized reading' (duration: 116.518422ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.769777Z","caller":"traceutil/trace.go:171","msg":"trace[527412069] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"172.956947ms","start":"2026-04-17T18:38:17.596758Z","end":"2026-04-17T18:38:17.769715Z","steps":["trace[527412069] 'process raft request' (duration: 162.156201ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.769851Z","caller":"traceutil/trace.go:171","msg":"trace[1062482480] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"172.223086ms","start":"2026-04-17T18:38:17.597607Z","end":"2026-04-17T18:38:17.76983Z","steps":["trace[1062482480] 'process raft request' (duration: 172.196235ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.769935Z","caller":"traceutil/trace.go:171","msg":"trace[1909644943] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"172.654508ms","start":"2026-04-17T18:38:17.597248Z","end":"2026-04-17T18:38:17.769903Z","steps":["trace[1909644943] 'process raft request' (duration: 172.451523ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.770161Z","caller":"traceutil/trace.go:171","msg":"trace[1955251278] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"172.597317ms","start":"2026-04-17T18:38:17.597554Z","end":"2026-04-17T18:38:17.770152Z","steps":["trace[1955251278] 'process raft request' (duration: 172.238626ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.770238Z","caller":"traceutil/trace.go:171","msg":"trace[63419440] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"172.987087ms","start":"2026-04-17T18:38:17.597241Z","end":"2026-04-17T18:38:17.770228Z","steps":["trace[63419440] 'process raft request' (duration: 172.399481ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.770366Z","caller":"traceutil/trace.go:171","msg":"trace[60095575] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"172.985598ms","start":"2026-04-17T18:38:17.597327Z","end":"2026-04-17T18:38:17.770313Z","steps":["trace[60095575] 'process raft request' (duration: 172.395702ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.770114Z","caller":"traceutil/trace.go:171","msg":"trace[1490772175] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"172.732391ms","start":"2026-04-17T18:38:17.597358Z","end":"2026-04-17T18:38:17.77009Z","steps":["trace[1490772175] 'process raft request' (duration: 172.420152ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.801966Z","caller":"traceutil/trace.go:171","msg":"trace[2126743831] linearizableReadLoop","detail":"{readStateIndex:30; appliedIndex:21; }","duration":"170.94885ms","start":"2026-04-17T18:38:17.630995Z","end":"2026-04-17T18:38:17.801944Z","steps":["trace[2126743831] 'read index received' (duration: 127.905354ms)","trace[2126743831] 'applied index is now lower than readState.Index' (duration: 43.042556ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:17.8021Z","caller":"traceutil/trace.go:171","msg":"trace[1213240393] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"171.579839ms","start":"2026-04-17T18:38:17.630493Z","end":"2026-04-17T18:38:17.802073Z","steps":["trace[1213240393] 'process raft request' (duration: 171.412004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:17.802199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.183976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:17.802244Z","caller":"traceutil/trace.go:171","msg":"trace[1903329171] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:26; }","duration":"171.27199ms","start":"2026-04-17T18:38:17.630956Z","end":"2026-04-17T18:38:17.802228Z","steps":["trace[1903329171] 'agreement among raft nodes before linearized reading' (duration: 171.107675ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:17.802626Z","caller":"traceutil/trace.go:171","msg":"trace[1083956082] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"171.798255ms","start":"2026-04-17T18:38:17.630214Z","end":"2026-04-17T18:38:17.802012Z","steps":["trace[1083956082] 'process raft request' (duration: 171.569158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:17.846834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.613924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:17.847159Z","caller":"traceutil/trace.go:171","msg":"trace[1915796209] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:31; }","duration":"197.976085ms","start":"2026-04-17T18:38:17.649163Z","end":"2026-04-17T18:38:17.84714Z","steps":["trace[1915796209] 'agreement among raft nodes before linearized reading' (duration: 197.552123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:18.502919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.43581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-17T18:38:18.503091Z","caller":"traceutil/trace.go:171","msg":"trace[224629939] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"635.615628ms","start":"2026-04-17T18:38:17.867457Z","end":"2026-04-17T18:38:18.503073Z","steps":["trace[224629939] 'process raft request' (duration: 635.566077ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:18.503194Z","caller":"traceutil/trace.go:171","msg":"trace[411354987] linearizableReadLoop","detail":"{readStateIndex:38; appliedIndex:37; }","duration":"636.736398ms","start":"2026-04-17T18:38:17.866425Z","end":"2026-04-17T18:38:18.503161Z","steps":["trace[411354987] 'read index received' (duration: 10.98254ms)","trace[411354987] 'applied index is now lower than readState.Index' (duration: 625.748418ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:38:18.503288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:17.867445Z","time spent":"635.743381ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":715,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:18.503385Z","caller":"traceutil/trace.go:171","msg":"trace[118226835] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"652.572917ms","start":"2026-04-17T18:38:17.85078Z","end":"2026-04-17T18:38:18.503353Z","steps":["trace[118226835] 'process raft request' (duration: 26.616973ms)","trace[118226835] 'compare' (duration: 625.317746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:38:18.503521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.079649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T18:38:18.503551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:17.850761Z","time spent":"652.696271ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:18.503573Z","caller":"traceutil/trace.go:171","msg":"trace[1243822583] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:35; }","duration":"637.180622ms","start":"2026-04-17T18:38:17.866377Z","end":"2026-04-17T18:38:18.503558Z","steps":["trace[1243822583] 'agreement among raft nodes before linearized reading' (duration: 636.970636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:18.503627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:17.866357Z","time spent":"637.249274ms","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":26,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-17T18:38:20.092087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000043388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-17T18:38:20.092214Z","caller":"traceutil/trace.go:171","msg":"trace[1694028664] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000228563s","start":"2026-04-17T18:38:18.091968Z","end":"2026-04-17T18:38:20.092196Z","steps":["trace[1694028664] 'agreement among raft nodes before linearized reading' (duration: 2.000081779s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:20.092263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:18.091942Z","time spent":"2.000310846s","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/17 18:38:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-17T18:38:20.3542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.662314324s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-17T18:38:20.354424Z","caller":"traceutil/trace.go:171","msg":"trace[1334911444] linearizableReadLoop","detail":"{readStateIndex:41; appliedIndex:39; }","duration":"1.851148879s","start":"2026-04-17T18:38:18.503258Z","end":"2026-04-17T18:38:20.354407Z","steps":["trace[1334911444] 'read index received' (duration: 188.63318ms)","trace[1334911444] 'applied index is now lower than readState.Index' (duration: 1.662514799s)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:20.354551Z","caller":"traceutil/trace.go:171","msg":"trace[676415518] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"1.846321963s","start":"2026-04-17T18:38:18.508206Z","end":"2026-04-17T18:38:20.354528Z","steps":["trace[676415518] 'process raft request' (duration: 1.846153507s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:20.354547Z","caller":"traceutil/trace.go:171","msg":"trace[1214666386] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"2.205240083s","start":"2026-04-17T18:38:18.149252Z","end":"2026-04-17T18:38:20.354492Z","steps":["trace[1214666386] 'process raft request' (duration: 542.555238ms)","trace[1214666386] 'compare' (duration: 1.66215147s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:38:20.354694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:18.508181Z","time spent":"1.846432506s","remote":"127.0.0.1:41802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":647,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:20.354712Z","caller":"traceutil/trace.go:171","msg":"trace[366252287] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"2.173791134s","start":"2026-04-17T18:38:18.180897Z","end":"2026-04-17T18:38:20.354688Z","steps":["trace[366252287] 'process raft request' (duration: 2.173412303s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:20.354809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:18.180864Z","time spent":"2.173900037s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:20.354814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:18.149212Z","time spent":"2.20550676s","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:20.354946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.487154168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:20.354994Z","caller":"traceutil/trace.go:171","msg":"trace[410583414] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:38; }","duration":"2.487225489s","start":"2026-04-17T18:38:17.867753Z","end":"2026-04-17T18:38:20.354979Z","steps":["trace[410583414] 'agreement among raft nodes before linearized reading' (duration: 2.487087906s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:20.355027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:17.867745Z","time spent":"2.487274341s","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" "} {"level":"warn","ts":"2026-04-17T18:38:20.355032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.452354774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:20.355106Z","caller":"traceutil/trace.go:171","msg":"trace[557956028] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:38; }","duration":"2.452470927s","start":"2026-04-17T18:38:17.902616Z","end":"2026-04-17T18:38:20.355087Z","steps":["trace[557956028] 'agreement among raft nodes before linearized reading' (duration: 2.451971604s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:20.355157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:17.902601Z","time spent":"2.452545829s","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-04-17T18:38:20.355245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.368885846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:20.355314Z","caller":"traceutil/trace.go:171","msg":"trace[2084108313] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:38; }","duration":"2.369179484s","start":"2026-04-17T18:38:17.986116Z","end":"2026-04-17T18:38:20.355296Z","steps":["trace[2084108313] 'agreement among raft nodes before linearized reading' (duration: 2.368817174s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:20.355314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.314660074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:20.35538Z","caller":"traceutil/trace.go:171","msg":"trace[1755588108] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:38; }","duration":"2.314776808s","start":"2026-04-17T18:38:18.040591Z","end":"2026-04-17T18:38:20.355368Z","steps":["trace[1755588108] 'agreement among raft nodes before linearized reading' (duration: 2.314689355s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:20.35538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:17.986096Z","time spent":"2.369269277s","remote":"127.0.0.1:41714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-17T18:38:20.355443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:18.040564Z","time spent":"2.314868799s","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-17T18:38:20.355575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256777118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:20.355642Z","caller":"traceutil/trace.go:171","msg":"trace[1940698178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:38; }","duration":"1.25688399s","start":"2026-04-17T18:38:19.098742Z","end":"2026-04-17T18:38:20.355626Z","steps":["trace[1940698178] 'agreement among raft nodes before linearized reading' (duration: 1.256775867s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:20.355687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:19.098712Z","time spent":"1.256962803s","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:38:20.355318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.679028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:20.355809Z","caller":"traceutil/trace.go:171","msg":"trace[374431041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:38; }","duration":"263.197854ms","start":"2026-04-17T18:38:20.092592Z","end":"2026-04-17T18:38:20.355789Z","steps":["trace[374431041] 'agreement among raft nodes before linearized reading' (duration: 262.698329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:20.355243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.369141633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:20.356119Z","caller":"traceutil/trace.go:171","msg":"trace[1715856931] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:38; }","duration":"2.369933415s","start":"2026-04-17T18:38:17.986054Z","end":"2026-04-17T18:38:20.355987Z","steps":["trace[1715856931] 'agreement among raft nodes before linearized reading' (duration: 2.369142742s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:20.356311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:17.986023Z","time spent":"2.370158532s","remote":"127.0.0.1:41692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-17T18:38:20.355324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.83246569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:20.356546Z","caller":"traceutil/trace.go:171","msg":"trace[258018666] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:38; }","duration":"1.833715276s","start":"2026-04-17T18:38:18.522803Z","end":"2026-04-17T18:38:20.356518Z","steps":["trace[258018666] 'agreement among raft nodes before linearized reading' (duration: 1.832471052s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:20.35663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:18.522781Z","time spent":"1.833830289s","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-17T18:38:20.35537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.910603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:20.356924Z","caller":"traceutil/trace.go:171","msg":"trace[42208609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:38; }","duration":"921.450756ms","start":"2026-04-17T18:38:19.435423Z","end":"2026-04-17T18:38:20.356873Z","steps":["trace[42208609] 'agreement among raft nodes before linearized reading' (duration: 919.930653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:20.356997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:19.435403Z","time spent":"921.55657ms","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:38:20.859835Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619741810096204209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:38:21.360922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619741810096204209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:38:21.862178Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619741810096204209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:38:22.258806Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.899928659s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T18:38:22.259298Z","caller":"traceutil/trace.go:171","msg":"trace[1384517616] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"1.900471135s","start":"2026-04-17T18:38:20.358785Z","end":"2026-04-17T18:38:22.259256Z","steps":["trace[1384517616] 'process raft request' (duration: 1.900241868s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.259469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:20.358761Z","time spent":"1.900631239s","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":350,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:22.491476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999833818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T18:38:22.491647Z","caller":"traceutil/trace.go:171","msg":"trace[1472899628] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000106126s","start":"2026-04-17T18:38:20.49152Z","end":"2026-04-17T18:38:22.491626Z","steps":["trace[1472899628] 'agreement among raft nodes before linearized reading' (duration: 1.99988439s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.491848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:20.491492Z","time spent":"2.000340773s","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/17 18:38:22 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-17T18:38:22.867253Z","caller":"traceutil/trace.go:171","msg":"trace[1497034812] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"2.506497924s","start":"2026-04-17T18:38:20.360716Z","end":"2026-04-17T18:38:22.867214Z","steps":["trace[1497034812] 'process raft request' (duration: 2.506229417s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:22.867311Z","caller":"traceutil/trace.go:171","msg":"trace[1789817994] linearizableReadLoop","detail":"{readStateIndex:48; appliedIndex:43; }","duration":"2.50776717s","start":"2026-04-17T18:38:20.359525Z","end":"2026-04-17T18:38:22.867292Z","steps":["trace[1789817994] 'read index received' (duration: 1.900446404s)","trace[1789817994] 'applied index is now lower than readState.Index' (duration: 607.319516ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:38:22.86741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:20.360699Z","time spent":"2.506633638s","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:22.868625Z","caller":"traceutil/trace.go:171","msg":"trace[2018930051] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"2.507519932s","start":"2026-04-17T18:38:20.36022Z","end":"2026-04-17T18:38:22.86774Z","steps":["trace[2018930051] 'process raft request' (duration: 2.501822911s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.868826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:20.3602Z","time spent":"2.50849654s","remote":"127.0.0.1:41714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:22.868848Z","caller":"traceutil/trace.go:171","msg":"trace[739594956] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"2.505733722s","start":"2026-04-17T18:38:20.36307Z","end":"2026-04-17T18:38:22.868804Z","steps":["trace[739594956] 'process raft request' (duration: 2.503941812s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.869393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:20.363028Z","time spent":"2.505902106s","remote":"127.0.0.1:41802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1058,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:38:22.870002Z","caller":"traceutil/trace.go:171","msg":"trace[244189449] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"2.506644458s","start":"2026-04-17T18:38:20.363208Z","end":"2026-04-17T18:38:22.869853Z","steps":["trace[244189449] 'process raft request' (duration: 2.503858619s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.870246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:20.363186Z","time spent":"2.506878585s","remote":"127.0.0.1:41802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:22.8703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.509941001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3614"} {"level":"info","ts":"2026-04-17T18:38:22.870627Z","caller":"traceutil/trace.go:171","msg":"trace[1634139288] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:44; }","duration":"2.510009873s","start":"2026-04-17T18:38:20.360323Z","end":"2026-04-17T18:38:22.870333Z","steps":["trace[1634139288] 'agreement among raft nodes before linearized reading' (duration: 2.509814668s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.870673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:20.360312Z","time spent":"2.510351883s","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3636,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-17T18:38:22.870971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.648577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T18:38:22.871036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.207441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:22.871115Z","caller":"traceutil/trace.go:171","msg":"trace[2101113369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44; }","duration":"936.314724ms","start":"2026-04-17T18:38:21.934777Z","end":"2026-04-17T18:38:22.871091Z","steps":["trace[2101113369] 'agreement among raft nodes before linearized reading' (duration: 936.230223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.87115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:21.934756Z","time spent":"936.386406ms","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T18:38:22.871269Z","caller":"traceutil/trace.go:171","msg":"trace[820918784] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:44; }","duration":"606.776241ms","start":"2026-04-17T18:38:22.264245Z","end":"2026-04-17T18:38:22.871022Z","steps":["trace[820918784] 'agreement among raft nodes before linearized reading' (duration: 606.368409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.871462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:22.264225Z","time spent":"607.101801ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" "} {"level":"warn","ts":"2026-04-17T18:38:22.872698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.510137337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3614"} {"level":"info","ts":"2026-04-17T18:38:22.872755Z","caller":"traceutil/trace.go:171","msg":"trace[340647761] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:44; }","duration":"2.510292561s","start":"2026-04-17T18:38:20.362452Z","end":"2026-04-17T18:38:22.872744Z","steps":["trace[340647761] 'agreement among raft nodes before linearized reading' (duration: 2.510079245s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.872779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:20.362408Z","time spent":"2.510365113s","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3636,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-17T18:38:22.873199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.022177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:22.873254Z","caller":"traceutil/trace.go:171","msg":"trace[1279034898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44; }","duration":"381.1368ms","start":"2026-04-17T18:38:22.492101Z","end":"2026-04-17T18:38:22.873238Z","steps":["trace[1279034898] 'agreement among raft nodes before linearized reading' (duration: 380.931204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.873347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:22.492083Z","time spent":"381.250664ms","remote":"127.0.0.1:41322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:38:22.873346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.513813071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:22.874553Z","caller":"traceutil/trace.go:171","msg":"trace[1858784995] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:44; }","duration":"2.515040535s","start":"2026-04-17T18:38:20.359494Z","end":"2026-04-17T18:38:22.874534Z","steps":["trace[1858784995] 'agreement among raft nodes before linearized reading' (duration: 2.513587214s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.874609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:20.35948Z","time spent":"2.515114927s","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-04-17T18:38:22.873502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.459697593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:22.874775Z","caller":"traceutil/trace.go:171","msg":"trace[1525669109] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:44; }","duration":"2.461322009s","start":"2026-04-17T18:38:20.413438Z","end":"2026-04-17T18:38:22.87476Z","steps":["trace[1525669109] 'agreement among raft nodes before linearized reading' (duration: 2.459399184s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.874804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:20.413415Z","time spent":"2.46138209s","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-04-17T18:38:22.873848Z","caller":"traceutil/trace.go:171","msg":"trace[1058347391] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"2.508074588s","start":"2026-04-17T18:38:20.365758Z","end":"2026-04-17T18:38:22.873833Z","steps":["trace[1058347391] 'process raft request' (duration: 2.501380719s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.875024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:20.365744Z","time spent":"2.509206171s","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3602,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:22.871565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.90333586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:38:22.875188Z","caller":"traceutil/trace.go:171","msg":"trace[1810028947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:44; }","duration":"1.906988043s","start":"2026-04-17T18:38:20.968187Z","end":"2026-04-17T18:38:22.875175Z","steps":["trace[1810028947] 'agreement among raft nodes before linearized reading' (duration: 1.903289899s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:22.875232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:20.968168Z","time spent":"1.907053705s","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:38:25.141543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.410752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:38:25.141669Z","caller":"traceutil/trace.go:171","msg":"trace[1584158711] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:260; }","duration":"185.037822ms","start":"2026-04-17T18:38:24.956615Z","end":"2026-04-17T18:38:25.141652Z","steps":["trace[1584158711] 'read index received' (duration: 51.591µs)","trace[1584158711] 'applied index is now lower than readState.Index' (duration: 184.984441ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:25.141722Z","caller":"traceutil/trace.go:171","msg":"trace[2072831189] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"256.53223ms","start":"2026-04-17T18:38:24.885154Z","end":"2026-04-17T18:38:25.141686Z","steps":["trace[2072831189] 'process raft request' (duration: 53.897521ms)","trace[2072831189] 'compare' (duration: 202.237057ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:38:25.141875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.257209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T18:38:25.141918Z","caller":"traceutil/trace.go:171","msg":"trace[2101155955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:255; }","duration":"185.335291ms","start":"2026-04-17T18:38:24.956569Z","end":"2026-04-17T18:38:25.141904Z","steps":["trace[2101155955] 'agreement among raft nodes before linearized reading' (duration: 185.173817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:25.142115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.206746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-17T18:38:25.142164Z","caller":"traceutil/trace.go:171","msg":"trace[1334414309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:255; }","duration":"180.317649ms","start":"2026-04-17T18:38:24.961834Z","end":"2026-04-17T18:38:25.142151Z","steps":["trace[1334414309] 'agreement among raft nodes before linearized reading' (duration: 180.218336ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:25.323906Z","caller":"traceutil/trace.go:171","msg":"trace[305930643] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"144.114187ms","start":"2026-04-17T18:38:25.179765Z","end":"2026-04-17T18:38:25.323879Z","steps":["trace[305930643] 'process raft request' (duration: 144.059625ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:25.324125Z","caller":"traceutil/trace.go:171","msg":"trace[1255179185] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"146.114784ms","start":"2026-04-17T18:38:25.177991Z","end":"2026-04-17T18:38:25.324106Z","steps":["trace[1255179185] 'process raft request' (duration: 139.864307ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:25.324445Z","caller":"traceutil/trace.go:171","msg":"trace[54453300] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"145.264789ms","start":"2026-04-17T18:38:25.179117Z","end":"2026-04-17T18:38:25.324381Z","steps":["trace[54453300] 'process raft request' (duration: 144.635712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:25.983006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.047641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:38:25.983213Z","caller":"traceutil/trace.go:171","msg":"trace[1802514883] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"192.38912ms","start":"2026-04-17T18:38:25.790807Z","end":"2026-04-17T18:38:25.983196Z","steps":["trace[1802514883] 'process raft request' (duration: 192.324957ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:25.98325Z","caller":"traceutil/trace.go:171","msg":"trace[357445721] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"259.914224ms","start":"2026-04-17T18:38:25.723304Z","end":"2026-04-17T18:38:25.983218Z","steps":["trace[357445721] 'process raft request' (duration: 96.587316ms)","trace[357445721] 'compare' (duration: 162.918286ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:25.983301Z","caller":"traceutil/trace.go:171","msg":"trace[687352920] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:287; }","duration":"192.656256ms","start":"2026-04-17T18:38:25.790608Z","end":"2026-04-17T18:38:25.983264Z","steps":["trace[687352920] 'read index received' (duration: 29.296456ms)","trace[687352920] 'applied index is now lower than readState.Index' (duration: 163.357049ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:38:25.98344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.80838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-17T18:38:25.983488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.867812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-17T18:38:25.983519Z","caller":"traceutil/trace.go:171","msg":"trace[124316214] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:283; }","duration":"192.939064ms","start":"2026-04-17T18:38:25.790569Z","end":"2026-04-17T18:38:25.983509Z","steps":["trace[124316214] 'agreement among raft nodes before linearized reading' (duration: 192.861762ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:25.983513Z","caller":"traceutil/trace.go:171","msg":"trace[559475577] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:283; }","duration":"192.928644ms","start":"2026-04-17T18:38:25.790566Z","end":"2026-04-17T18:38:25.983495Z","steps":["trace[559475577] 'agreement among raft nodes before linearized reading' (duration: 192.745779ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:26.257351Z","caller":"traceutil/trace.go:171","msg":"trace[396972360] transaction","detail":"{read_only:false; number_of_response:0; response_revision:283; }","duration":"374.312291ms","start":"2026-04-17T18:38:25.883014Z","end":"2026-04-17T18:38:26.257326Z","steps":["trace[396972360] 'process raft request' (duration: 373.085496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:26.257497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:25.882983Z","time spent":"374.443675ms","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:26.319614Z","caller":"traceutil/trace.go:171","msg":"trace[1968285331] transaction","detail":"{read_only:false; number_of_response:0; response_revision:283; }","duration":"421.366988ms","start":"2026-04-17T18:38:25.89822Z","end":"2026-04-17T18:38:26.319587Z","steps":["trace[1968285331] 'process raft request' (duration: 421.170813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:26.319927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:25.898181Z","time spent":"421.571735ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:26.320107Z","caller":"traceutil/trace.go:171","msg":"trace[362252162] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"421.092381ms","start":"2026-04-17T18:38:25.898984Z","end":"2026-04-17T18:38:26.320077Z","steps":["trace[362252162] 'process raft request' (duration: 420.522715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:26.32026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:25.89897Z","time spent":"421.227844ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:26.320492Z","caller":"traceutil/trace.go:171","msg":"trace[557016132] transaction","detail":"{read_only:false; number_of_response:0; response_revision:284; }","duration":"421.41049ms","start":"2026-04-17T18:38:25.899063Z","end":"2026-04-17T18:38:26.320473Z","steps":["trace[557016132] 'process raft request' (duration: 421.116151ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:26.320573Z","caller":"traceutil/trace.go:171","msg":"trace[473869272] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"421.08969ms","start":"2026-04-17T18:38:25.899472Z","end":"2026-04-17T18:38:26.320561Z","steps":["trace[473869272] 'process raft request' (duration: 420.994598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:26.320597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:25.899037Z","time spent":"421.524643ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:26.320626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:25.89946Z","time spent":"421.143093ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:26.320727Z","caller":"traceutil/trace.go:171","msg":"trace[461887722] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"421.571965ms","start":"2026-04-17T18:38:25.899146Z","end":"2026-04-17T18:38:26.320718Z","steps":["trace[461887722] 'process raft request' (duration: 421.265816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:26.320774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:25.899117Z","time spent":"421.636136ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:26.320798Z","caller":"traceutil/trace.go:171","msg":"trace[11908068] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"333.824548ms","start":"2026-04-17T18:38:25.986961Z","end":"2026-04-17T18:38:26.320785Z","steps":["trace[11908068] 'process raft request' (duration: 333.54186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:26.32085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:25.986937Z","time spent":"333.88698ms","remote":"127.0.0.1:41692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":373,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:26.320923Z","caller":"traceutil/trace.go:171","msg":"trace[1515152202] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"332.727288ms","start":"2026-04-17T18:38:25.988185Z","end":"2026-04-17T18:38:26.320912Z","steps":["trace[1515152202] 'process raft request' (duration: 332.350197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:26.320979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:25.988169Z","time spent":"332.784419ms","remote":"127.0.0.1:41400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:26.32102Z","caller":"traceutil/trace.go:171","msg":"trace[1420129366] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:289; }","duration":"337.559774ms","start":"2026-04-17T18:38:25.983401Z","end":"2026-04-17T18:38:26.32096Z","steps":["trace[1420129366] 'read index received' (duration: 272.796927ms)","trace[1420129366] 'applied index is now lower than readState.Index' (duration: 64.761877ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:26.321104Z","caller":"traceutil/trace.go:171","msg":"trace[914882643] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"329.840635ms","start":"2026-04-17T18:38:25.991249Z","end":"2026-04-17T18:38:26.321089Z","steps":["trace[914882643] 'process raft request' (duration: 329.594379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:26.321353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.2737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:38:26.321401Z","caller":"traceutil/trace.go:171","msg":"trace[607371169] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:289; }","duration":"329.351202ms","start":"2026-04-17T18:38:25.992035Z","end":"2026-04-17T18:38:26.321386Z","steps":["trace[607371169] 'agreement among raft nodes before linearized reading' (duration: 329.269249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:26.321385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.582349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-17T18:38:26.321702Z","caller":"traceutil/trace.go:171","msg":"trace[241731835] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:289; }","duration":"530.972841ms","start":"2026-04-17T18:38:25.790713Z","end":"2026-04-17T18:38:26.321686Z","steps":["trace[241731835] 'agreement among raft nodes before linearized reading' (duration: 530.489486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:26.321766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:25.991223Z","time spent":"330.135144ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:26.321782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:25.790706Z","time spent":"531.062374ms","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-17T18:38:26.321449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:25.992026Z","time spent":"329.414174ms","remote":"127.0.0.1:41516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" "} {"level":"info","ts":"2026-04-17T18:38:26.781327Z","caller":"traceutil/trace.go:171","msg":"trace[790555950] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"158.623966ms","start":"2026-04-17T18:38:26.622679Z","end":"2026-04-17T18:38:26.781303Z","steps":["trace[790555950] 'process raft request' (duration: 158.475051ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:26.993475Z","caller":"traceutil/trace.go:171","msg":"trace[2067503786] transaction","detail":"{read_only:false; number_of_response:0; response_revision:310; }","duration":"176.988514ms","start":"2026-04-17T18:38:26.816465Z","end":"2026-04-17T18:38:26.993453Z","steps":["trace[2067503786] 'process raft request' (duration: 171.042675ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:27.133886Z","caller":"traceutil/trace.go:171","msg":"trace[1953299508] transaction","detail":"{read_only:false; number_of_response:0; response_revision:310; }","duration":"316.827858ms","start":"2026-04-17T18:38:26.817028Z","end":"2026-04-17T18:38:27.133856Z","steps":["trace[1953299508] 'process raft request' (duration: 316.659854ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:27.133973Z","caller":"traceutil/trace.go:171","msg":"trace[851895269] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:319; }","duration":"315.723647ms","start":"2026-04-17T18:38:26.818222Z","end":"2026-04-17T18:38:27.133946Z","steps":["trace[851895269] 'read index received' (duration: 169.312317ms)","trace[851895269] 'applied index is now lower than readState.Index' (duration: 146.4104ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:27.134063Z","caller":"traceutil/trace.go:171","msg":"trace[1719567137] transaction","detail":"{read_only:false; number_of_response:0; response_revision:310; }","duration":"315.571193ms","start":"2026-04-17T18:38:26.818486Z","end":"2026-04-17T18:38:27.134057Z","steps":["trace[1719567137] 'process raft request' (duration: 315.348597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:27.134097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:26.818478Z","time spent":"315.606693ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:27.134184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.073085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:38:27.134026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:26.816998Z","time spent":"316.963942ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:27.134238Z","caller":"traceutil/trace.go:171","msg":"trace[968513556] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:0; response_revision:311; }","duration":"312.157877ms","start":"2026-04-17T18:38:26.822062Z","end":"2026-04-17T18:38:27.13422Z","steps":["trace[968513556] 'agreement among raft nodes before linearized reading' (duration: 312.062184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:27.134237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.979795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T18:38:27.134292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:26.822053Z","time spent":"312.226928ms","remote":"127.0.0.1:41516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"info","ts":"2026-04-17T18:38:27.134345Z","caller":"traceutil/trace.go:171","msg":"trace[1353960858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:311; }","duration":"316.130959ms","start":"2026-04-17T18:38:26.818188Z","end":"2026-04-17T18:38:27.134319Z","steps":["trace[1353960858] 'agreement among raft nodes before linearized reading' (duration: 315.942773ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:27.134387Z","caller":"traceutil/trace.go:171","msg":"trace[920018004] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"314.313377ms","start":"2026-04-17T18:38:26.820065Z","end":"2026-04-17T18:38:27.134378Z","steps":["trace[920018004] 'process raft request' (duration: 313.796042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:27.134408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:26.818178Z","time spent":"316.21833ms","remote":"127.0.0.1:41592","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-04-17T18:38:27.134452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:26.820054Z","time spent":"314.359519ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:27.134561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.783953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"info","ts":"2026-04-17T18:38:27.134654Z","caller":"traceutil/trace.go:171","msg":"trace[1010567161] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:311; }","duration":"139.898867ms","start":"2026-04-17T18:38:26.994745Z","end":"2026-04-17T18:38:27.134644Z","steps":["trace[1010567161] 'agreement among raft nodes before linearized reading' (duration: 139.766723ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:27.134755Z","caller":"traceutil/trace.go:171","msg":"trace[1167589707] transaction","detail":"{read_only:false; number_of_response:0; response_revision:310; }","duration":"316.438267ms","start":"2026-04-17T18:38:26.817467Z","end":"2026-04-17T18:38:27.133905Z","steps":["trace[1167589707] 'process raft request' (duration: 316.323654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:27.134919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:26.817456Z","time spent":"317.390615ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:27.138768Z","caller":"traceutil/trace.go:171","msg":"trace[1595734015] transaction","detail":"{read_only:false; number_of_response:0; response_revision:310; }","duration":"315.975884ms","start":"2026-04-17T18:38:26.818234Z","end":"2026-04-17T18:38:27.13421Z","steps":["trace[1595734015] 'process raft request' (duration: 315.582212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:27.139166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:26.81822Z","time spent":"320.865193ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:27.346015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.535803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:38:27.346168Z","caller":"traceutil/trace.go:171","msg":"trace[1534174817] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"149.686421ms","start":"2026-04-17T18:38:27.196452Z","end":"2026-04-17T18:38:27.346138Z","steps":["trace[1534174817] 'process raft request' (duration: 41.914762ms)","trace[1534174817] 'compare' (duration: 107.361638ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:27.36765Z","caller":"traceutil/trace.go:171","msg":"trace[399947201] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:328; }","duration":"170.20877ms","start":"2026-04-17T18:38:27.197416Z","end":"2026-04-17T18:38:27.367624Z","steps":["trace[399947201] 'read index received' (duration: 41.001975ms)","trace[399947201] 'applied index is now lower than readState.Index' (duration: 129.205695ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:27.367762Z","caller":"traceutil/trace.go:171","msg":"trace[754812472] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"170.839067ms","start":"2026-04-17T18:38:27.196882Z","end":"2026-04-17T18:38:27.367722Z","steps":["trace[754812472] 'process raft request' (duration: 170.583091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:27.367853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.061536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-04-17T18:38:27.367888Z","caller":"traceutil/trace.go:171","msg":"trace[344620364] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:316; }","duration":"170.146328ms","start":"2026-04-17T18:38:27.197731Z","end":"2026-04-17T18:38:27.367878Z","steps":["trace[344620364] 'agreement among raft nodes before linearized reading' (duration: 170.036945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:27.36786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.411967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:38:27.367952Z","caller":"traceutil/trace.go:171","msg":"trace[433584494] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:316; }","duration":"170.56998ms","start":"2026-04-17T18:38:27.197361Z","end":"2026-04-17T18:38:27.367931Z","steps":["trace[433584494] 'agreement among raft nodes before linearized reading' (duration: 170.400135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:28.179214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.731951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:38:28.17938Z","caller":"traceutil/trace.go:171","msg":"trace[377744017] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"664.875014ms","start":"2026-04-17T18:38:27.514468Z","end":"2026-04-17T18:38:28.179343Z","steps":["trace[377744017] 'process raft request' (duration: 323.922317ms)","trace[377744017] 'compare' (duration: 340.525754ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:38:28.179455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:27.514448Z","time spent":"664.984738ms","remote":"127.0.0.1:41400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:28.227478Z","caller":"traceutil/trace.go:171","msg":"trace[1495885354] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"712.681083ms","start":"2026-04-17T18:38:27.514759Z","end":"2026-04-17T18:38:28.22744Z","steps":["trace[1495885354] 'process raft request' (duration: 712.473237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:28.227634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:27.514746Z","time spent":"712.819297ms","remote":"127.0.0.1:41516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:28.227732Z","caller":"traceutil/trace.go:171","msg":"trace[1127838178] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"710.304506ms","start":"2026-04-17T18:38:27.517417Z","end":"2026-04-17T18:38:28.227722Z","steps":["trace[1127838178] 'process raft request' (duration: 709.939836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:28.227846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:27.517378Z","time spent":"710.388937ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:38:28.227492Z","caller":"traceutil/trace.go:171","msg":"trace[427293216] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"705.533211ms","start":"2026-04-17T18:38:27.521932Z","end":"2026-04-17T18:38:28.227465Z","steps":["trace[427293216] 'process raft request' (duration: 705.47896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:28.227949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:27.521903Z","time spent":"705.996294ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:28.596922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.101577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:38:28.597036Z","caller":"traceutil/trace.go:171","msg":"trace[967577723] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:338; }","duration":"361.818034ms","start":"2026-04-17T18:38:28.235195Z","end":"2026-04-17T18:38:28.597013Z","steps":["trace[967577723] 'read index received' (duration: 90.511312ms)","trace[967577723] 'applied index is now lower than readState.Index' (duration: 271.304652ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:28.597509Z","caller":"traceutil/trace.go:171","msg":"trace[1058128304] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"609.798818ms","start":"2026-04-17T18:38:27.987691Z","end":"2026-04-17T18:38:28.597489Z","steps":["trace[1058128304] 'process raft request' (duration: 338.045934ms)","trace[1058128304] 'compare' (duration: 270.973352ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:38:28.597716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:27.987662Z","time spent":"609.906642ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:28.598106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.906696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"info","ts":"2026-04-17T18:38:28.598149Z","caller":"traceutil/trace.go:171","msg":"trace[1121225588] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:325; }","duration":"362.999729ms","start":"2026-04-17T18:38:28.235138Z","end":"2026-04-17T18:38:28.598138Z","steps":["trace[1121225588] 'agreement among raft nodes before linearized reading' (duration: 362.921856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:28.598233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:28.23511Z","time spent":"363.112282ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5176,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-04-17T18:38:28.598546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.261957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-17T18:38:28.598599Z","caller":"traceutil/trace.go:171","msg":"trace[791970657] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:325; }","duration":"363.354509ms","start":"2026-04-17T18:38:28.235223Z","end":"2026-04-17T18:38:28.598578Z","steps":["trace[791970657] 'agreement among raft nodes before linearized reading' (duration: 363.281127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:28.598729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:28.235196Z","time spent":"363.504332ms","remote":"127.0.0.1:41516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-04-17T18:38:30.724753Z","caller":"traceutil/trace.go:171","msg":"trace[2114827644] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"259.29985ms","start":"2026-04-17T18:38:30.465423Z","end":"2026-04-17T18:38:30.724722Z","steps":["trace[2114827644] 'process raft request' (duration: 259.104154ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:31.956611Z","caller":"traceutil/trace.go:171","msg":"trace[701071702] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"114.433326ms","start":"2026-04-17T18:38:31.842154Z","end":"2026-04-17T18:38:31.956587Z","steps":["trace[701071702] 'process raft request' (duration: 114.249081ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:46.066334Z","caller":"traceutil/trace.go:171","msg":"trace[1717185747] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"466.377424ms","start":"2026-04-17T18:38:45.599933Z","end":"2026-04-17T18:38:46.06631Z","steps":["trace[1717185747] 'read index received' (duration: 466.166028ms)","trace[1717185747] 'applied index is now lower than readState.Index' (duration: 210.446µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:46.066387Z","caller":"traceutil/trace.go:171","msg":"trace[1626231124] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"477.9456ms","start":"2026-04-17T18:38:45.588412Z","end":"2026-04-17T18:38:46.066357Z","steps":["trace[1626231124] 'process raft request' (duration: 477.768994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:46.066554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:45.588378Z","time spent":"478.093444ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:46.066536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.571649ms","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-04-17T18:38:46.066635Z","caller":"traceutil/trace.go:171","msg":"trace[1791295167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"466.713334ms","start":"2026-04-17T18:38:45.599901Z","end":"2026-04-17T18:38:46.066614Z","steps":["trace[1791295167] 'agreement among raft nodes before linearized reading' (duration: 466.516458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:46.066685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:45.599889Z","time spent":"466.787037ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:38:46.276776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.789537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:38:46.276885Z","caller":"traceutil/trace.go:171","msg":"trace[1377630386] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"204.687851ms","start":"2026-04-17T18:38:46.07218Z","end":"2026-04-17T18:38:46.276868Z","steps":["trace[1377630386] 'process raft request' (duration: 55.731798ms)","trace[1377630386] 'compare' (duration: 148.686354ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:38:46.505086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.605597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:38:46.505172Z","caller":"traceutil/trace.go:171","msg":"trace[78047912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"123.722141ms","start":"2026-04-17T18:38:46.381429Z","end":"2026-04-17T18:38:46.505151Z","steps":["trace[78047912] 'range keys from in-memory index tree' (duration: 123.547386ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:48.285429Z","caller":"traceutil/trace.go:171","msg":"trace[939026430] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"210.486112ms","start":"2026-04-17T18:38:48.074912Z","end":"2026-04-17T18:38:48.285398Z","steps":["trace[939026430] 'process raft request' (duration: 210.246425ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:51.648195Z","caller":"traceutil/trace.go:171","msg":"trace[261148649] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"176.994428ms","start":"2026-04-17T18:38:51.471171Z","end":"2026-04-17T18:38:51.648165Z","steps":["trace[261148649] 'process raft request' (duration: 174.982151ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:51.664332Z","caller":"traceutil/trace.go:171","msg":"trace[728082667] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"192.509715ms","start":"2026-04-17T18:38:51.471801Z","end":"2026-04-17T18:38:51.664311Z","steps":["trace[728082667] 'process raft request' (duration: 192.421062ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:51.867432Z","caller":"traceutil/trace.go:171","msg":"trace[1946484339] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"114.783077ms","start":"2026-04-17T18:38:51.752619Z","end":"2026-04-17T18:38:51.867402Z","steps":["trace[1946484339] 'process raft request' (duration: 113.828531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:53.880037Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619741810096205149,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:38:54.380619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619741810096205149,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:38:54.444164Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.072478915s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T18:38:54.44484Z","caller":"traceutil/trace.go:171","msg":"trace[1062891227] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.073274767s","start":"2026-04-17T18:38:53.371537Z","end":"2026-04-17T18:38:54.444812Z","steps":["trace[1062891227] 'process raft request' (duration: 1.072871625s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:54.445094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.371509Z","time spent":"1.07340938s","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:38:54.501366Z","caller":"traceutil/trace.go:171","msg":"trace[1141105061] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"983.584114ms","start":"2026-04-17T18:38:53.517738Z","end":"2026-04-17T18:38:54.501322Z","steps":["trace[1141105061] 'process raft request' (duration: 983.367468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:54.501576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.517715Z","time spent":"983.7781ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:54.502654Z","caller":"traceutil/trace.go:171","msg":"trace[1476413304] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"977.297226ms","start":"2026-04-17T18:38:53.525171Z","end":"2026-04-17T18:38:54.502468Z","steps":["trace[1476413304] 'process raft request' (duration: 976.085353ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:54.502754Z","caller":"traceutil/trace.go:171","msg":"trace[1581185146] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"964.743184ms","start":"2026-04-17T18:38:53.537867Z","end":"2026-04-17T18:38:54.502611Z","steps":["trace[1581185146] 'process raft request' (duration: 963.414456ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:54.502577Z","caller":"traceutil/trace.go:171","msg":"trace[1389645587] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"962.633824ms","start":"2026-04-17T18:38:53.539819Z","end":"2026-04-17T18:38:54.502452Z","steps":["trace[1389645587] 'process raft request' (duration: 961.523683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:54.503054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.525158Z","time spent":"977.617816ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:54.503094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.539809Z","time spent":"963.225092ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:54.503445Z","caller":"traceutil/trace.go:171","msg":"trace[1455943610] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"983.127822ms","start":"2026-04-17T18:38:53.520194Z","end":"2026-04-17T18:38:54.503322Z","steps":["trace[1455943610] 'process raft request' (duration: 981.030783ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:54.503538Z","caller":"traceutil/trace.go:171","msg":"trace[87500009] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"952.902231ms","start":"2026-04-17T18:38:53.550608Z","end":"2026-04-17T18:38:54.50351Z","steps":["trace[87500009] 'process raft request' (duration: 950.940146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:54.503559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.520172Z","time spent":"983.323657ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:54.503625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.550597Z","time spent":"952.993464ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:54.503638Z","caller":"traceutil/trace.go:171","msg":"trace[1580393128] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"963.297584ms","start":"2026-04-17T18:38:53.540294Z","end":"2026-04-17T18:38:54.503591Z","steps":["trace[1580393128] 'process raft request' (duration: 961.136043ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:54.50387Z","caller":"traceutil/trace.go:171","msg":"trace[1749499973] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"956.094892ms","start":"2026-04-17T18:38:53.547618Z","end":"2026-04-17T18:38:54.503712Z","steps":["trace[1749499973] 'process raft request' (duration: 953.873159ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:54.503978Z","caller":"traceutil/trace.go:171","msg":"trace[386986108] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"931.248522ms","start":"2026-04-17T18:38:53.572656Z","end":"2026-04-17T18:38:54.503905Z","steps":["trace[386986108] 'process raft request' (duration: 928.969498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:54.504069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.540286Z","time spent":"963.401437ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:54.5043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.537845Z","time spent":"965.095285ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:54.50449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.572639Z","time spent":"931.455318ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:54.504651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.547566Z","time spent":"956.342989ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:54.505022Z","caller":"traceutil/trace.go:171","msg":"trace[1496461163] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"931.423478ms","start":"2026-04-17T18:38:53.573437Z","end":"2026-04-17T18:38:54.504861Z","steps":["trace[1496461163] 'process raft request' (duration: 928.286418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:54.505239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.57343Z","time spent":"931.711235ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:54.506557Z","caller":"traceutil/trace.go:171","msg":"trace[186466222] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"908.922585ms","start":"2026-04-17T18:38:53.597617Z","end":"2026-04-17T18:38:54.50654Z","steps":["trace[186466222] 'process raft request' (duration: 908.458752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:54.506643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.597599Z","time spent":"909.002607ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:38:54.507576Z","caller":"traceutil/trace.go:171","msg":"trace[1357456083] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:534; }","duration":"1.128577361s","start":"2026-04-17T18:38:53.378943Z","end":"2026-04-17T18:38:54.50752Z","steps":["trace[1357456083] 'read index received' (duration: 1.065609031s)","trace[1357456083] 'applied index is now lower than readState.Index' (duration: 62.96687ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:54.507751Z","caller":"traceutil/trace.go:171","msg":"trace[646905126] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"804.54774ms","start":"2026-04-17T18:38:53.703171Z","end":"2026-04-17T18:38:54.507719Z","steps":["trace[646905126] 'process raft request' (duration: 804.144139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:54.507834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.703145Z","time spent":"804.645334ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:38:54.508167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129183918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:38:54.508298Z","caller":"traceutil/trace.go:171","msg":"trace[269280842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"1.129386954s","start":"2026-04-17T18:38:53.378895Z","end":"2026-04-17T18:38:54.508282Z","steps":["trace[269280842] 'agreement among raft nodes before linearized reading' (duration: 1.129140697s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:54.508346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.378877Z","time spent":"1.129456156s","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:38:54.515845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.344161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.82\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T18:38:54.515894Z","caller":"traceutil/trace.go:171","msg":"trace[371720086] range","detail":"{range_begin:/registry/masterleases/199.19.213.82; range_end:; response_count:1; response_revision:527; }","duration":"620.450674ms","start":"2026-04-17T18:38:53.895433Z","end":"2026-04-17T18:38:54.515883Z","steps":["trace[371720086] 'agreement among raft nodes before linearized reading' (duration: 613.203091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:54.515916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:53.895415Z","time spent":"620.497545ms","remote":"127.0.0.1:41354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.82\" "} {"level":"warn","ts":"2026-04-17T18:38:54.516206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.454487ms","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-04-17T18:38:54.516263Z","caller":"traceutil/trace.go:171","msg":"trace[867483809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"141.552529ms","start":"2026-04-17T18:38:54.374698Z","end":"2026-04-17T18:38:54.51625Z","steps":["trace[867483809] 'agreement among raft nodes before linearized reading' (duration: 134.000427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:54.516344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.107639ms","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-04-17T18:38:54.516376Z","caller":"traceutil/trace.go:171","msg":"trace[879663528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"188.161529ms","start":"2026-04-17T18:38:54.328207Z","end":"2026-04-17T18:38:54.516368Z","steps":["trace[879663528] 'agreement among raft nodes before linearized reading' (duration: 180.495004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:54.516525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.393279ms","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-04-17T18:38:54.516619Z","caller":"traceutil/trace.go:171","msg":"trace[1724415406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"192.532043ms","start":"2026-04-17T18:38:54.324072Z","end":"2026-04-17T18:38:54.516604Z","steps":["trace[1724415406] 'agreement among raft nodes before linearized reading' (duration: 184.729713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:57.626238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.97453261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-17T18:38:57.626313Z","caller":"traceutil/trace.go:171","msg":"trace[1223049319] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:591; }","duration":"1.974637493s","start":"2026-04-17T18:38:55.651659Z","end":"2026-04-17T18:38:57.626297Z","steps":["trace[1223049319] 'range keys from in-memory index tree' (duration: 1.974285694s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:57.626397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:55.651646Z","time spent":"1.974739846s","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151258,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-17T18:38:57.627171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707320758s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:38:57.627287Z","caller":"traceutil/trace.go:171","msg":"trace[1148954475] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"1.245443065s","start":"2026-04-17T18:38:56.381832Z","end":"2026-04-17T18:38:57.627275Z","steps":["trace[1148954475] 'read index received' (duration: 36.55µs)","trace[1148954475] 'applied index is now lower than readState.Index' (duration: 1.245405615s)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:38:57.627289Z","caller":"traceutil/trace.go:171","msg":"trace[1420969902] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.974752166s","start":"2026-04-17T18:38:55.652489Z","end":"2026-04-17T18:38:57.627241Z","steps":["trace[1420969902] 'process raft request' (duration: 267.300304ms)","trace[1420969902] 'compare' (duration: 1.706911807s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:38:57.627416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:55.652479Z","time spent":"1.974866009s","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:38:57.627492Z","caller":"traceutil/trace.go:171","msg":"trace[13757825] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"836.776708ms","start":"2026-04-17T18:38:56.790695Z","end":"2026-04-17T18:38:57.627471Z","steps":["trace[13757825] 'process raft request' (duration: 836.538791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:57.627571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:56.790667Z","time spent":"836.869111ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:38:57.627624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063163032s","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-04-17T18:38:57.627668Z","caller":"traceutil/trace.go:171","msg":"trace[1971617108] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"1.063233024s","start":"2026-04-17T18:38:56.564423Z","end":"2026-04-17T18:38:57.627656Z","steps":["trace[1971617108] 'agreement among raft nodes before linearized reading' (duration: 1.0631011s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:57.627701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:56.564407Z","time spent":"1.063285916s","remote":"127.0.0.1:41592","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-04-17T18:38:57.627705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245866558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:38:57.627729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086775987s","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-04-17T18:38:57.627776Z","caller":"traceutil/trace.go:171","msg":"trace[1136494267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"1.245910179s","start":"2026-04-17T18:38:56.381825Z","end":"2026-04-17T18:38:57.627735Z","steps":["trace[1136494267] 'agreement among raft nodes before linearized reading' (duration: 1.245519588s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:38:57.627786Z","caller":"traceutil/trace.go:171","msg":"trace[2137990300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"1.086867188s","start":"2026-04-17T18:38:56.540904Z","end":"2026-04-17T18:38:57.627771Z","steps":["trace[2137990300] 'agreement among raft nodes before linearized reading' (duration: 1.086697534s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:57.627816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:56.381771Z","time spent":"1.246036172s","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:38:57.627828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050719023s","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-04-17T18:38:57.6279Z","caller":"traceutil/trace.go:171","msg":"trace[633583481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"1.050812844s","start":"2026-04-17T18:38:56.577077Z","end":"2026-04-17T18:38:57.62789Z","steps":["trace[633583481] 'agreement among raft nodes before linearized reading' (duration: 1.050688701s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:57.627934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:56.577064Z","time spent":"1.050861527s","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:38:57.627823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:56.540884Z","time spent":"1.086931501s","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:38:57.628351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.179240174s","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-04-17T18:38:57.628431Z","caller":"traceutil/trace.go:171","msg":"trace[4046888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"1.179365188s","start":"2026-04-17T18:38:56.449044Z","end":"2026-04-17T18:38:57.62841Z","steps":["trace[4046888] 'agreement among raft nodes before linearized reading' (duration: 1.178411511s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:38:57.62861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:38:56.449026Z","time spent":"1.179564884s","remote":"127.0.0.1:41592","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-04-17T18:39:01.521928Z","caller":"traceutil/trace.go:171","msg":"trace[374590609] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"418.780987ms","start":"2026-04-17T18:39:01.103107Z","end":"2026-04-17T18:39:01.521888Z","steps":["trace[374590609] 'process raft request' (duration: 413.053056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:01.522608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:01.103081Z","time spent":"419.416384ms","remote":"127.0.0.1:41416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036201,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:39:02.321955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.439294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:39:02.322125Z","caller":"traceutil/trace.go:171","msg":"trace[2007489222] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"347.865524ms","start":"2026-04-17T18:39:01.974244Z","end":"2026-04-17T18:39:02.32211Z","steps":["trace[2007489222] 'process raft request' (duration: 347.788641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:02.322214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:01.974219Z","time spent":"347.936615ms","remote":"127.0.0.1:41400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:02.322334Z","caller":"traceutil/trace.go:171","msg":"trace[1629878690] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"646.491531ms","start":"2026-04-17T18:39:01.675836Z","end":"2026-04-17T18:39:02.322328Z","steps":["trace[1629878690] 'read index received' (duration: 289.671766ms)","trace[1629878690] 'applied index is now lower than readState.Index' (duration: 356.818695ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:02.32251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.671896ms","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-04-17T18:39:02.322536Z","caller":"traceutil/trace.go:171","msg":"trace[1050552047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"646.725387ms","start":"2026-04-17T18:39:01.675802Z","end":"2026-04-17T18:39:02.322528Z","steps":["trace[1050552047] 'agreement among raft nodes before linearized reading' (duration: 646.611584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:02.322557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:01.675785Z","time spent":"646.767479ms","remote":"127.0.0.1:41592","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-04-17T18:39:02.322606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.27432ms","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-04-17T18:39:02.32268Z","caller":"traceutil/trace.go:171","msg":"trace[793371283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"638.371103ms","start":"2026-04-17T18:39:01.684293Z","end":"2026-04-17T18:39:02.322664Z","steps":["trace[793371283] 'agreement among raft nodes before linearized reading' (duration: 638.223778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:02.322715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:01.684284Z","time spent":"638.423884ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:39:02.322726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.881262ms","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-04-17T18:39:02.322758Z","caller":"traceutil/trace.go:171","msg":"trace[1733544206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"400.914573ms","start":"2026-04-17T18:39:01.921835Z","end":"2026-04-17T18:39:02.322749Z","steps":["trace[1733544206] 'agreement among raft nodes before linearized reading' (duration: 400.861822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:02.322761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.421715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-17T18:39:02.32278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:01.921769Z","time spent":"401.006226ms","remote":"127.0.0.1:41592","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-04-17T18:39:02.322784Z","caller":"traceutil/trace.go:171","msg":"trace[1635921195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"638.486296ms","start":"2026-04-17T18:39:01.684293Z","end":"2026-04-17T18:39:02.322779Z","steps":["trace[1635921195] 'agreement among raft nodes before linearized reading' (duration: 638.415694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:02.322815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:01.684269Z","time spent":"638.541177ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T18:39:02.322883Z","caller":"traceutil/trace.go:171","msg":"trace[2064946709] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"744.414293ms","start":"2026-04-17T18:39:01.578449Z","end":"2026-04-17T18:39:02.322863Z","steps":["trace[2064946709] 'process raft request' (duration: 387.019792ms)","trace[2064946709] 'compare' (duration: 356.166566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:02.322942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:01.578411Z","time spent":"744.495414ms","remote":"127.0.0.1:41678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:03.529104Z","caller":"traceutil/trace.go:171","msg":"trace[302921507] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"963.900905ms","start":"2026-04-17T18:39:02.565175Z","end":"2026-04-17T18:39:03.529076Z","steps":["trace[302921507] 'process raft request' (duration: 963.771212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:03.529255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:02.565154Z","time spent":"964.021829ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:39:04.037362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619741810096205385,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T18:39:04.269398Z","caller":"traceutil/trace.go:171","msg":"trace[2101228182] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"732.365542ms","start":"2026-04-17T18:39:03.537006Z","end":"2026-04-17T18:39:04.269372Z","steps":["trace[2101228182] 'read index received' (duration: 730.578251ms)","trace[2101228182] 'applied index is now lower than readState.Index' (duration: 1.786581ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:04.269484Z","caller":"traceutil/trace.go:171","msg":"trace[1772552282] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.487181974s","start":"2026-04-17T18:39:02.782273Z","end":"2026-04-17T18:39:04.269455Z","steps":["trace[1772552282] 'process raft request' (duration: 1.486019652s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:04.269623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.588888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:39:04.269672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.013336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.82\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T18:39:04.269713Z","caller":"traceutil/trace.go:171","msg":"trace[1932179396] range","detail":"{range_begin:/registry/masterleases/199.19.213.82; range_end:; response_count:1; response_revision:623; }","duration":"374.103588ms","start":"2026-04-17T18:39:03.895599Z","end":"2026-04-17T18:39:04.269702Z","steps":["trace[1932179396] 'agreement among raft nodes before linearized reading' (duration: 373.929153ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:04.26972Z","caller":"traceutil/trace.go:171","msg":"trace[1396336105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"732.743792ms","start":"2026-04-17T18:39:03.536954Z","end":"2026-04-17T18:39:04.269698Z","steps":["trace[1396336105] 'agreement among raft nodes before linearized reading' (duration: 732.532366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:04.269745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:03.89558Z","time spent":"374.15854ms","remote":"127.0.0.1:41354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.82\" "} {"level":"warn","ts":"2026-04-17T18:39:04.269776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:03.536937Z","time spent":"732.829423ms","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:39:04.269796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:02.782242Z","time spent":"1.487307087s","remote":"127.0.0.1:41416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036241,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:39:04.831268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619741810096205394,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:39:05.332309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619741810096205394,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:39:05.832772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619741810096205394,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:39:06.333841Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619741810096205394,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:39:06.776019Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.445725919s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T18:39:06.776864Z","caller":"traceutil/trace.go:171","msg":"trace[523318518] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"2.446187812s","start":"2026-04-17T18:39:04.330656Z","end":"2026-04-17T18:39:06.776844Z","steps":["trace[523318518] 'read index received' (duration: 2.445804621s)","trace[523318518] 'applied index is now lower than readState.Index' (duration: 382.241µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:06.77704Z","caller":"traceutil/trace.go:171","msg":"trace[685010879] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"2.446747938s","start":"2026-04-17T18:39:04.330191Z","end":"2026-04-17T18:39:06.776939Z","steps":["trace[685010879] 'process raft request' (duration: 2.446441749s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:06.777166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:04.330169Z","time spent":"2.446943023s","remote":"127.0.0.1:41692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:39:06.777221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.420190031s","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-04-17T18:39:06.77726Z","caller":"traceutil/trace.go:171","msg":"trace[1868791743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"2.420270535s","start":"2026-04-17T18:39:04.356981Z","end":"2026-04-17T18:39:06.777252Z","steps":["trace[1868791743] 'agreement among raft nodes before linearized reading' (duration: 2.420185632s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:06.777287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:04.356955Z","time spent":"2.420327275s","remote":"127.0.0.1:41592","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-04-17T18:39:06.777281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.420023687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T18:39:06.777293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.434753501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-17T18:39:06.77732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.396513751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:39:06.777347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.420758308s","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-04-17T18:39:06.777372Z","caller":"traceutil/trace.go:171","msg":"trace[1972799117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"2.434854633s","start":"2026-04-17T18:39:04.342482Z","end":"2026-04-17T18:39:06.777336Z","steps":["trace[1972799117] 'agreement among raft nodes before linearized reading' (duration: 2.434675379s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:06.777396Z","caller":"traceutil/trace.go:171","msg":"trace[1426745432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"1.396630696s","start":"2026-04-17T18:39:05.380745Z","end":"2026-04-17T18:39:06.777375Z","steps":["trace[1426745432] 'agreement among raft nodes before linearized reading' (duration: 1.396524493s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:06.777394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.446711457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T18:39:06.777337Z","caller":"traceutil/trace.go:171","msg":"trace[1045933985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"2.420093188s","start":"2026-04-17T18:39:04.35723Z","end":"2026-04-17T18:39:06.777323Z","steps":["trace[1045933985] 'agreement among raft nodes before linearized reading' (duration: 2.419929544s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:06.777455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:05.380713Z","time spent":"1.396730827s","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:39:06.777442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:04.342414Z","time spent":"2.435016228s","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T18:39:06.777509Z","caller":"traceutil/trace.go:171","msg":"trace[175881653] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:625; }","duration":"2.446868522s","start":"2026-04-17T18:39:04.330607Z","end":"2026-04-17T18:39:06.777476Z","steps":["trace[175881653] 'agreement among raft nodes before linearized reading' (duration: 2.44644068s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:06.777503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:04.357223Z","time spent":"2.420267973s","remote":"127.0.0.1:41592","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-04-17T18:39:06.777569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:04.330599Z","time spent":"2.446961404s","remote":"127.0.0.1:41354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-04-17T18:39:06.777443Z","caller":"traceutil/trace.go:171","msg":"trace[1714855895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"2.420894371s","start":"2026-04-17T18:39:04.356527Z","end":"2026-04-17T18:39:06.777421Z","steps":["trace[1714855895] 'agreement among raft nodes before linearized reading' (duration: 2.420649854s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:06.777734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:04.356508Z","time spent":"2.42120987s","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T18:39:07.350201Z","caller":"traceutil/trace.go:171","msg":"trace[568756707] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"143.226452ms","start":"2026-04-17T18:39:07.206943Z","end":"2026-04-17T18:39:07.35017Z","steps":["trace[568756707] 'process raft request' (duration: 142.421729ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:08.177205Z","caller":"traceutil/trace.go:171","msg":"trace[1557888695] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"314.100151ms","start":"2026-04-17T18:39:07.863068Z","end":"2026-04-17T18:39:08.177168Z","steps":["trace[1557888695] 'process raft request' (duration: 312.30429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:08.177872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:07.863044Z","time spent":"314.267844ms","remote":"127.0.0.1:41416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:39:08.180783Z","caller":"traceutil/trace.go:171","msg":"trace[876803303] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"267.030699ms","start":"2026-04-17T18:39:07.913726Z","end":"2026-04-17T18:39:08.180757Z","steps":["trace[876803303] 'process raft request' (duration: 266.811483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:09.118831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.808184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-17T18:39:09.119312Z","caller":"traceutil/trace.go:171","msg":"trace[2091093358] transaction","detail":"{read_only:false; number_of_response:0; response_revision:654; }","duration":"425.399844ms","start":"2026-04-17T18:39:08.693882Z","end":"2026-04-17T18:39:09.119282Z","steps":["trace[2091093358] 'process raft request' (duration: 227.067845ms)","trace[2091093358] 'compare' (duration: 197.754152ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:09.119428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:08.693864Z","time spent":"425.509887ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:09.119842Z","caller":"traceutil/trace.go:171","msg":"trace[2025729171] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"424.827888ms","start":"2026-04-17T18:39:08.694983Z","end":"2026-04-17T18:39:09.11981Z","steps":["trace[2025729171] 'process raft request' (duration: 423.999915ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:09.120052Z","caller":"traceutil/trace.go:171","msg":"trace[916464551] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"264.175188ms","start":"2026-04-17T18:39:08.855872Z","end":"2026-04-17T18:39:09.120047Z","steps":["trace[916464551] 'process raft request' (duration: 263.944941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:09.120242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:08.694951Z","time spent":"425.173178ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:39:09.12Z","caller":"traceutil/trace.go:171","msg":"trace[262787442] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"421.601318ms","start":"2026-04-17T18:39:08.69839Z","end":"2026-04-17T18:39:09.119991Z","steps":["trace[262787442] 'process raft request' (duration: 421.197766ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:09.120027Z","caller":"traceutil/trace.go:171","msg":"trace[718044162] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"381.487901ms","start":"2026-04-17T18:39:08.738534Z","end":"2026-04-17T18:39:09.120022Z","steps":["trace[718044162] 'process raft request' (duration: 381.124011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:09.120561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.299368ms","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-04-17T18:39:09.120593Z","caller":"traceutil/trace.go:171","msg":"trace[117436005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:658; }","duration":"322.375742ms","start":"2026-04-17T18:39:08.79821Z","end":"2026-04-17T18:39:09.120586Z","steps":["trace[117436005] 'agreement among raft nodes before linearized reading' (duration: 322.276549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:09.120613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:08.798188Z","time spent":"322.419763ms","remote":"127.0.0.1:41592","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":"info","ts":"2026-04-17T18:39:09.120472Z","caller":"traceutil/trace.go:171","msg":"trace[1534150120] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:680; }","duration":"322.196676ms","start":"2026-04-17T18:39:08.798266Z","end":"2026-04-17T18:39:09.120463Z","steps":["trace[1534150120] 'read index received' (duration: 122.89657ms)","trace[1534150120] 'applied index is now lower than readState.Index' (duration: 199.299316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:09.120719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:08.738506Z","time spent":"382.079798ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:39:09.120652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:08.698367Z","time spent":"422.136443ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:39:09.121224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.483229ms","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-04-17T18:39:09.121263Z","caller":"traceutil/trace.go:171","msg":"trace[442342641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:659; }","duration":"320.537789ms","start":"2026-04-17T18:39:08.800713Z","end":"2026-04-17T18:39:09.121251Z","steps":["trace[442342641] 'agreement among raft nodes before linearized reading' (duration: 320.406616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:09.121496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:08.800703Z","time spent":"320.786627ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:39:09.121482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.696454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T18:39:09.121425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.523009ms","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-04-17T18:39:09.121546Z","caller":"traceutil/trace.go:171","msg":"trace[1900870451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:659; }","duration":"320.766835ms","start":"2026-04-17T18:39:08.800759Z","end":"2026-04-17T18:39:09.121526Z","steps":["trace[1900870451] 'agreement among raft nodes before linearized reading' (duration: 320.631102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:09.121579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:08.800753Z","time spent":"320.818727ms","remote":"127.0.0.1:41592","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-04-17T18:39:09.12159Z","caller":"traceutil/trace.go:171","msg":"trace[1180627834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"320.682214ms","start":"2026-04-17T18:39:08.80087Z","end":"2026-04-17T18:39:09.121553Z","steps":["trace[1180627834] 'agreement among raft nodes before linearized reading' (duration: 320.498978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:09.121742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:08.800856Z","time spent":"320.826967ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T18:39:22.833506Z","caller":"traceutil/trace.go:171","msg":"trace[444123687] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"172.136849ms","start":"2026-04-17T18:39:22.661347Z","end":"2026-04-17T18:39:22.833484Z","steps":["trace[444123687] 'read index received' (duration: 171.949244ms)","trace[444123687] 'applied index is now lower than readState.Index' (duration: 186.745µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:22.833705Z","caller":"traceutil/trace.go:171","msg":"trace[1406983589] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"185.483643ms","start":"2026-04-17T18:39:22.648206Z","end":"2026-04-17T18:39:22.83369Z","steps":["trace[1406983589] 'process raft request' (duration: 185.138963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:22.833795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.627782ms","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-04-17T18:39:22.833852Z","caller":"traceutil/trace.go:171","msg":"trace[1691341062] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:790; }","duration":"110.735496ms","start":"2026-04-17T18:39:22.723103Z","end":"2026-04-17T18:39:22.833839Z","steps":["trace[1691341062] 'agreement among raft nodes before linearized reading' (duration: 110.51038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:22.834347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.686094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:39:22.834435Z","caller":"traceutil/trace.go:171","msg":"trace[347668774] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:790; }","duration":"173.080145ms","start":"2026-04-17T18:39:22.661336Z","end":"2026-04-17T18:39:22.834416Z","steps":["trace[347668774] 'agreement among raft nodes before linearized reading' (duration: 172.288213ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:22.963723Z","caller":"traceutil/trace.go:171","msg":"trace[1226065248] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"123.524535ms","start":"2026-04-17T18:39:22.840172Z","end":"2026-04-17T18:39:22.963696Z","steps":["trace[1226065248] 'process raft request' (duration: 123.320429ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:22.964968Z","caller":"traceutil/trace.go:171","msg":"trace[1479032511] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"124.534653ms","start":"2026-04-17T18:39:22.840403Z","end":"2026-04-17T18:39:22.964938Z","steps":["trace[1479032511] 'process raft request' (duration: 124.385719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:23.423208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.371462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:39:23.423316Z","caller":"traceutil/trace.go:171","msg":"trace[172419470] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"233.53378ms","start":"2026-04-17T18:39:23.189765Z","end":"2026-04-17T18:39:23.423298Z","steps":["trace[172419470] 'read index received' (duration: 65.062925ms)","trace[172419470] 'applied index is now lower than readState.Index' (duration: 168.469615ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:23.423431Z","caller":"traceutil/trace.go:171","msg":"trace[1446783568] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"448.891051ms","start":"2026-04-17T18:39:22.974507Z","end":"2026-04-17T18:39:23.423398Z","steps":["trace[1446783568] 'process raft request' (duration: 280.276202ms)","trace[1446783568] 'compare' (duration: 168.258469ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:23.42357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:22.974479Z","time spent":"449.027734ms","remote":"127.0.0.1:41416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4423,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:39:23.423684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.976802ms","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-04-17T18:39:23.423737Z","caller":"traceutil/trace.go:171","msg":"trace[2105606462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"224.051834ms","start":"2026-04-17T18:39:23.199676Z","end":"2026-04-17T18:39:23.423727Z","steps":["trace[2105606462] 'agreement among raft nodes before linearized reading' (duration: 223.952981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:23.423679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.871269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T18:39:23.423908Z","caller":"traceutil/trace.go:171","msg":"trace[440928740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"234.183948ms","start":"2026-04-17T18:39:23.189717Z","end":"2026-04-17T18:39:23.423901Z","steps":["trace[440928740] 'agreement among raft nodes before linearized reading' (duration: 233.674814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:23.423895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.378089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T18:39:23.424002Z","caller":"traceutil/trace.go:171","msg":"trace[1308505955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"211.541623ms","start":"2026-04-17T18:39:23.212444Z","end":"2026-04-17T18:39:23.423985Z","steps":["trace[1308505955] 'agreement among raft nodes before linearized reading' (duration: 211.323687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:23.424004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.345629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-17T18:39:23.424226Z","caller":"traceutil/trace.go:171","msg":"trace[1621853774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"210.629238ms","start":"2026-04-17T18:39:23.213577Z","end":"2026-04-17T18:39:23.424206Z","steps":["trace[1621853774] 'agreement among raft nodes before linearized reading' (duration: 210.092883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:23.736424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.296506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:39:23.736638Z","caller":"traceutil/trace.go:171","msg":"trace[1516059663] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"308.276658ms","start":"2026-04-17T18:39:23.42834Z","end":"2026-04-17T18:39:23.736616Z","steps":["trace[1516059663] 'process raft request' (duration: 111.699143ms)","trace[1516059663] 'compare' (duration: 196.156203ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:23.737388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:23.428311Z","time spent":"308.927496ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:39:23.813172Z","caller":"traceutil/trace.go:171","msg":"trace[692037591] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"384.69245ms","start":"2026-04-17T18:39:23.428454Z","end":"2026-04-17T18:39:23.813146Z","steps":["trace[692037591] 'process raft request' (duration: 384.515615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:23.813371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:23.428436Z","time spent":"384.816424ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:39:23.813683Z","caller":"traceutil/trace.go:171","msg":"trace[723915978] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"385.006569ms","start":"2026-04-17T18:39:23.428642Z","end":"2026-04-17T18:39:23.813649Z","steps":["trace[723915978] 'process raft request' (duration: 384.451684ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:23.813755Z","caller":"traceutil/trace.go:171","msg":"trace[985255418] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"384.754812ms","start":"2026-04-17T18:39:23.428987Z","end":"2026-04-17T18:39:23.813742Z","steps":["trace[985255418] 'process raft request' (duration: 384.511305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:23.813822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:23.428978Z","time spent":"384.809044ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:39:23.813895Z","caller":"traceutil/trace.go:171","msg":"trace[662687457] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"382.477769ms","start":"2026-04-17T18:39:23.431358Z","end":"2026-04-17T18:39:23.813836Z","steps":["trace[662687457] 'process raft request' (duration: 382.298133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:23.81392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:23.428635Z","time spent":"385.134902ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:39:23.815157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:23.431351Z","time spent":"382.635242ms","remote":"127.0.0.1:41416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4445,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:23.966382Z","caller":"traceutil/trace.go:171","msg":"trace[676190649] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"143.605348ms","start":"2026-04-17T18:39:23.822743Z","end":"2026-04-17T18:39:23.966348Z","steps":["trace[676190649] 'process raft request' (duration: 84.47277ms)","trace[676190649] 'compare' (duration: 58.83743ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:24.168118Z","caller":"traceutil/trace.go:171","msg":"trace[677967660] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:829; }","duration":"270.845616ms","start":"2026-04-17T18:39:23.897239Z","end":"2026-04-17T18:39:24.168084Z","steps":["trace[677967660] 'read index received' (duration: 9.99062ms)","trace[677967660] 'applied index is now lower than readState.Index' (duration: 260.853436ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:24.168237Z","caller":"traceutil/trace.go:171","msg":"trace[2111626303] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"326.137467ms","start":"2026-04-17T18:39:23.842071Z","end":"2026-04-17T18:39:24.168209Z","steps":["trace[2111626303] 'process raft request' (duration: 325.818648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:24.168493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.394665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:1 size:1642"} {"level":"warn","ts":"2026-04-17T18:39:24.168518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:23.842044Z","time spent":"326.26611ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:39:24.168508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.236898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.82\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T18:39:24.168563Z","caller":"traceutil/trace.go:171","msg":"trace[1769021929] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:1; response_revision:800; }","duration":"198.521748ms","start":"2026-04-17T18:39:23.970024Z","end":"2026-04-17T18:39:24.168546Z","steps":["trace[1769021929] 'agreement among raft nodes before linearized reading' (duration: 198.287141ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:24.16862Z","caller":"traceutil/trace.go:171","msg":"trace[770486883] range","detail":"{range_begin:/registry/masterleases/199.19.213.82; range_end:; response_count:1; response_revision:800; }","duration":"271.377472ms","start":"2026-04-17T18:39:23.897216Z","end":"2026-04-17T18:39:24.168593Z","steps":["trace[770486883] 'agreement among raft nodes before linearized reading' (duration: 271.028842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:25.881542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619741810096205738,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:39:26.304086Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.327111708s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T18:39:26.304791Z","caller":"traceutil/trace.go:171","msg":"trace[684375026] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"923.654693ms","start":"2026-04-17T18:39:25.381074Z","end":"2026-04-17T18:39:26.304729Z","steps":["trace[684375026] 'read index received' (duration: 923.328534ms)","trace[684375026] 'applied index is now lower than readState.Index' (duration: 324.929µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:26.305105Z","caller":"traceutil/trace.go:171","msg":"trace[195605275] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"1.328228019s","start":"2026-04-17T18:39:24.976838Z","end":"2026-04-17T18:39:26.305066Z","steps":["trace[195605275] 'process raft request' (duration: 1.327699904s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:26.305293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:24.976815Z","time spent":"1.328391863s","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:39:26.305361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.359261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T18:39:26.305444Z","caller":"traceutil/trace.go:171","msg":"trace[1227941634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:803; }","duration":"562.484234ms","start":"2026-04-17T18:39:25.742942Z","end":"2026-04-17T18:39:26.305426Z","steps":["trace[1227941634] 'agreement among raft nodes before linearized reading' (duration: 562.29184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:26.305499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:25.742917Z","time spent":"562.569036ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:39:26.305141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.055975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:39:26.305578Z","caller":"traceutil/trace.go:171","msg":"trace[2087794478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"924.544778ms","start":"2026-04-17T18:39:25.381017Z","end":"2026-04-17T18:39:26.305561Z","steps":["trace[2087794478] 'agreement among raft nodes before linearized reading' (duration: 924.008453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:26.30568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:25.380991Z","time spent":"924.63528ms","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:39:26.305809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.254413ms","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-04-17T18:39:26.305817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.518832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-17T18:39:26.306076Z","caller":"traceutil/trace.go:171","msg":"trace[2098749548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:803; }","duration":"487.848871ms","start":"2026-04-17T18:39:25.818205Z","end":"2026-04-17T18:39:26.306054Z","steps":["trace[2098749548] 'agreement among raft nodes before linearized reading' (duration: 487.248905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:26.306147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:25.818196Z","time spent":"487.926874ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T18:39:26.305864Z","caller":"traceutil/trace.go:171","msg":"trace[695776021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:803; }","duration":"488.337654ms","start":"2026-04-17T18:39:25.817513Z","end":"2026-04-17T18:39:26.305851Z","steps":["trace[695776021] 'agreement among raft nodes before linearized reading' (duration: 488.199191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:26.306242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:25.817501Z","time spent":"488.729356ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T18:39:26.305952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.52056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:39:26.306343Z","caller":"traceutil/trace.go:171","msg":"trace[644928836] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:803; }","duration":"304.955022ms","start":"2026-04-17T18:39:26.001372Z","end":"2026-04-17T18:39:26.306327Z","steps":["trace[644928836] 'agreement among raft nodes before linearized reading' (duration: 304.52594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:26.306377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:26.001316Z","time spent":"305.013473ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:39:26.306727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.658615ms","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-04-17T18:39:26.306808Z","caller":"traceutil/trace.go:171","msg":"trace[407694644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:803; }","duration":"489.24196ms","start":"2026-04-17T18:39:25.817513Z","end":"2026-04-17T18:39:26.306755Z","steps":["trace[407694644] 'agreement among raft nodes before linearized reading' (duration: 488.376656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:26.307001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:25.817498Z","time spent":"489.470307ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T18:39:43.004796Z","caller":"traceutil/trace.go:171","msg":"trace[535611453] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"115.395224ms","start":"2026-04-17T18:39:42.889367Z","end":"2026-04-17T18:39:43.004762Z","steps":["trace[535611453] 'process raft request' (duration: 113.884762ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:49.61677Z","caller":"traceutil/trace.go:171","msg":"trace[909582809] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"157.185442ms","start":"2026-04-17T18:39:49.459561Z","end":"2026-04-17T18:39:49.616747Z","steps":["trace[909582809] 'process raft request' (duration: 157.030588ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:40:04.872084Z","caller":"traceutil/trace.go:171","msg":"trace[138244194] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"220.277307ms","start":"2026-04-17T18:40:04.651784Z","end":"2026-04-17T18:40:04.872061Z","steps":["trace[138244194] 'process raft request' (duration: 124.127405ms)","trace[138244194] 'compare' (duration: 96.062559ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:40:20.306275Z","caller":"traceutil/trace.go:171","msg":"trace[286584563] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"168.058813ms","start":"2026-04-17T18:40:20.138183Z","end":"2026-04-17T18:40:20.306242Z","steps":["trace[286584563] 'process raft request' (duration: 167.519348ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:40:20.793897Z","caller":"traceutil/trace.go:171","msg":"trace[487416211] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"111.927932ms","start":"2026-04-17T18:40:20.681932Z","end":"2026-04-17T18:40:20.79386Z","steps":["trace[487416211] 'process raft request' (duration: 108.103505ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:40:36.656759Z","caller":"traceutil/trace.go:171","msg":"trace[1018230410] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"191.828495ms","start":"2026-04-17T18:40:36.464589Z","end":"2026-04-17T18:40:36.656418Z","steps":["trace[1018230410] 'process raft request' (duration: 191.65797ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:40:56.918347Z","caller":"traceutil/trace.go:171","msg":"trace[1267794653] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"176.050173ms","start":"2026-04-17T18:40:56.742272Z","end":"2026-04-17T18:40:56.918322Z","steps":["trace[1267794653] 'process raft request' (duration: 175.903568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:41:01.162112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.111955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-17T18:41:01.162147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.48639ms","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-04-17T18:41:01.162226Z","caller":"traceutil/trace.go:171","msg":"trace[1209209571] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1243; }","duration":"107.297809ms","start":"2026-04-17T18:41:01.054906Z","end":"2026-04-17T18:41:01.162204Z","steps":["trace[1209209571] 'range keys from in-memory index tree' (duration: 106.907598ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:01.162234Z","caller":"traceutil/trace.go:171","msg":"trace[2127264481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1243; }","duration":"105.622312ms","start":"2026-04-17T18:41:01.056592Z","end":"2026-04-17T18:41:01.162214Z","steps":["trace[2127264481] 'range keys from in-memory index tree' (duration: 105.326335ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:03.077432Z","caller":"traceutil/trace.go:171","msg":"trace[822334682] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"103.451042ms","start":"2026-04-17T18:41:02.973956Z","end":"2026-04-17T18:41:03.077407Z","steps":["trace[822334682] 'process raft request' (duration: 103.320269ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:13.315428Z","caller":"traceutil/trace.go:171","msg":"trace[1183307406] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"180.606039ms","start":"2026-04-17T18:41:13.134788Z","end":"2026-04-17T18:41:13.315394Z","steps":["trace[1183307406] 'read index received' (duration: 180.400333ms)","trace[1183307406] 'applied index is now lower than readState.Index' (duration: 204.906µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:41:13.315537Z","caller":"traceutil/trace.go:171","msg":"trace[1473625007] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"210.310379ms","start":"2026-04-17T18:41:13.105216Z","end":"2026-04-17T18:41:13.315526Z","steps":["trace[1473625007] 'process raft request' (duration: 210.038481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:41:13.315686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.884527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:41:13.315728Z","caller":"traceutil/trace.go:171","msg":"trace[1728989380] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1315; }","duration":"180.970699ms","start":"2026-04-17T18:41:13.134739Z","end":"2026-04-17T18:41:13.31571Z","steps":["trace[1728989380] 'agreement among raft nodes before linearized reading' (duration: 180.899196ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:21.450456Z","caller":"traceutil/trace.go:171","msg":"trace[721610076] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"100.987662ms","start":"2026-04-17T18:41:21.349453Z","end":"2026-04-17T18:41:21.450441Z","steps":["trace[721610076] 'read index received' (duration: 100.847829ms)","trace[721610076] 'applied index is now lower than readState.Index' (duration: 139.193µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:41:21.450567Z","caller":"traceutil/trace.go:171","msg":"trace[1289040366] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"102.466434ms","start":"2026-04-17T18:41:21.348068Z","end":"2026-04-17T18:41:21.450534Z","steps":["trace[1289040366] 'process raft request' (duration: 102.27682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:41:21.450785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.324362ms","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-04-17T18:41:21.450818Z","caller":"traceutil/trace.go:171","msg":"trace[1937907558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1346; }","duration":"101.369903ms","start":"2026-04-17T18:41:21.34944Z","end":"2026-04-17T18:41:21.45081Z","steps":["trace[1937907558] 'agreement among raft nodes before linearized reading' (duration: 101.090725ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:21.631509Z","caller":"traceutil/trace.go:171","msg":"trace[1214622958] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"176.647808ms","start":"2026-04-17T18:41:21.454814Z","end":"2026-04-17T18:41:21.631461Z","steps":["trace[1214622958] 'process raft request' (duration: 165.498586ms)","trace[1214622958] 'compare' (duration: 10.887865ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:41:21.646567Z","caller":"traceutil/trace.go:171","msg":"trace[592108698] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"191.307648ms","start":"2026-04-17T18:41:21.455172Z","end":"2026-04-17T18:41:21.646479Z","steps":["trace[592108698] 'process raft request' (duration: 191.247216ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:21.646573Z","caller":"traceutil/trace.go:171","msg":"trace[331562433] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"191.39183ms","start":"2026-04-17T18:41:21.455154Z","end":"2026-04-17T18:41:21.646545Z","steps":["trace[331562433] 'process raft request' (duration: 191.137873ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:21.646788Z","caller":"traceutil/trace.go:171","msg":"trace[791210242] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"191.334808ms","start":"2026-04-17T18:41:21.455445Z","end":"2026-04-17T18:41:21.64678Z","steps":["trace[791210242] 'process raft request' (duration: 191.008799ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:21.646937Z","caller":"traceutil/trace.go:171","msg":"trace[623927920] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"191.157313ms","start":"2026-04-17T18:41:21.455754Z","end":"2026-04-17T18:41:21.646911Z","steps":["trace[623927920] 'process raft request' (duration: 190.979548ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:22.662032Z","caller":"traceutil/trace.go:171","msg":"trace[914268026] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"165.29835ms","start":"2026-04-17T18:41:22.496704Z","end":"2026-04-17T18:41:22.662003Z","steps":["trace[914268026] 'process raft request' (duration: 165.105605ms)"],"step_count":1}