{"level":"warn","ts":"2026-04-17T18:39:17.773631Z","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:39:17.773827Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.9: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.9:2380","--initial-cluster=instance=https://199.19.213.9:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.9:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.9: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:39:17.773976Z","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:39:17.774001Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.9:2380"]} {"level":"info","ts":"2026-04-17T18:39:17.774065Z","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:39:17.775705Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"]} {"level":"info","ts":"2026-04-17T18:39:17.775871Z","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.9:2380"],"listen-peer-urls":["https://199.19.213.9:2380"],"advertise-client-urls":["https://199.19.213.9:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.9: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:39:17.782485Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.167303ms"} {"level":"info","ts":"2026-04-17T18:39:17.795731Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3a0c9d65f167b31b","cluster-id":"86e523b598166645"} {"level":"info","ts":"2026-04-17T18:39:17.795864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T18:39:17.795898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became follower at term 0"} {"level":"info","ts":"2026-04-17T18:39:17.795916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3a0c9d65f167b31b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T18:39:17.795927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became follower at term 1"} {"level":"info","ts":"2026-04-17T18:39:17.796003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=(4182891215087711003)"} {"level":"warn","ts":"2026-04-17T18:39:17.811884Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T18:39:17.813721Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T18:39:17.815132Z","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:39:17.818806Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3a0c9d65f167b31b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T18:39:17.819392Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3a0c9d65f167b31b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T18:39:17.819707Z","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:39:17.819785Z","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:39:17.819795Z","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:39:17.822355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=(4182891215087711003)"} {"level":"info","ts":"2026-04-17T18:39:17.822686Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"86e523b598166645","local-member-id":"3a0c9d65f167b31b","added-peer-id":"3a0c9d65f167b31b","added-peer-peer-urls":["https://199.19.213.9:2380"]} {"level":"info","ts":"2026-04-17T18:39:17.824686Z","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:39:17.824836Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.9:2380"} {"level":"info","ts":"2026-04-17T18:39:17.824932Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.9:2380"} {"level":"info","ts":"2026-04-17T18:39:17.825653Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3a0c9d65f167b31b","initial-advertise-peer-urls":["https://199.19.213.9:2380"],"listen-peer-urls":["https://199.19.213.9:2380"],"advertise-client-urls":["https://199.19.213.9:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T18:39:17.825696Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T18:39:17.898291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T18:39:17.898353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T18:39:17.898388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b received MsgPreVoteResp from 3a0c9d65f167b31b at term 1"} {"level":"info","ts":"2026-04-17T18:39:17.898411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became candidate at term 2"} {"level":"info","ts":"2026-04-17T18:39:17.898429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b received MsgVoteResp from 3a0c9d65f167b31b at term 2"} {"level":"info","ts":"2026-04-17T18:39:17.898453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became leader at term 2"} {"level":"info","ts":"2026-04-17T18:39:17.898474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3a0c9d65f167b31b elected leader 3a0c9d65f167b31b at term 2"} {"level":"info","ts":"2026-04-17T18:39:17.899648Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:39:17.900323Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3a0c9d65f167b31b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.9:2379]}","request-path":"/0/members/3a0c9d65f167b31b/attributes","cluster-id":"86e523b598166645","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T18:39:17.90037Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:39:17.900936Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:39:17.90126Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T18:39:17.901381Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T18:39:17.901532Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"86e523b598166645","local-member-id":"3a0c9d65f167b31b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:39:17.901627Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:39:17.901659Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:39:17.904697Z","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:39:17.90476Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.9:2379"} {"level":"warn","ts":"2026-04-17T18:39:21.968183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166635837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T18:39:21.968283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.908948243s","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:39:21.968311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203086867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T18:39:21.968286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260957379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T18:39:21.968335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260845833s","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:39:21.968339Z","caller":"traceutil/trace.go:171","msg":"trace[907405562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:69; }","duration":"1.166882248s","start":"2026-04-17T18:39:20.801432Z","end":"2026-04-17T18:39:21.968314Z","steps":["trace[907405562] 'range keys from in-memory index tree' (duration: 1.166507281s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:21.968375Z","caller":"traceutil/trace.go:171","msg":"trace[1482455126] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:69; }","duration":"1.909124851s","start":"2026-04-17T18:39:20.059228Z","end":"2026-04-17T18:39:21.968353Z","steps":["trace[1482455126] 'range keys from in-memory index tree' (duration: 1.908802316s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:21.968385Z","caller":"traceutil/trace.go:171","msg":"trace[666362709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:69; }","duration":"1.203207993s","start":"2026-04-17T18:39:20.76516Z","end":"2026-04-17T18:39:21.968368Z","steps":["trace[666362709] 'range keys from in-memory index tree' (duration: 1.203008753s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:21.968415Z","caller":"traceutil/trace.go:171","msg":"trace[410687233] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:69; }","duration":"1.260956648s","start":"2026-04-17T18:39:20.707441Z","end":"2026-04-17T18:39:21.968398Z","steps":["trace[410687233] 'range keys from in-memory index tree' (duration: 1.260800661s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:21.968421Z","caller":"traceutil/trace.go:171","msg":"trace[1366799888] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:69; }","duration":"1.261166589s","start":"2026-04-17T18:39:20.707221Z","end":"2026-04-17T18:39:21.968387Z","steps":["trace[1366799888] 'range keys from in-memory index tree' (duration: 1.260874156s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:21.968432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:20.76514Z","time spent":"1.203280456s","remote":"127.0.0.1:55102","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:21.968463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:20.707435Z","time spent":"1.26100684s","remote":"127.0.0.1:55462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-17T18:39:21.96843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:20.059188Z","time spent":"1.909231646s","remote":"127.0.0.1:55474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-17T18:39:21.968431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:20.801385Z","time spent":"1.167030585s","remote":"127.0.0.1:55118","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:21.9685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:20.707201Z","time spent":"1.261283444s","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-17T18:39:22.475241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12906082453679973847,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T18:39:22.967089Z","caller":"traceutil/trace.go:171","msg":"trace[836817661] linearizableReadLoop","detail":"{readStateIndex:74; appliedIndex:73; }","duration":"992.883351ms","start":"2026-04-17T18:39:21.974181Z","end":"2026-04-17T18:39:22.967064Z","steps":["trace[836817661] 'read index received' (duration: 992.632799ms)","trace[836817661] 'applied index is now lower than readState.Index' (duration: 249.542µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:22.967147Z","caller":"traceutil/trace.go:171","msg":"trace[1821889272] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"994.037866ms","start":"2026-04-17T18:39:21.973084Z","end":"2026-04-17T18:39:22.967122Z","steps":["trace[1821889272] 'process raft request' (duration: 993.810015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:22.967289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.08655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:39:22.967335Z","caller":"traceutil/trace.go:171","msg":"trace[1997287642] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:70; }","duration":"993.145783ms","start":"2026-04-17T18:39:21.974178Z","end":"2026-04-17T18:39:22.967323Z","steps":["trace[1997287642] 'agreement among raft nodes before linearized reading' (duration: 993.011267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:22.967376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:21.974136Z","time spent":"993.231387ms","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-04-17T18:39:22.967651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:21.972869Z","time spent":"994.332109ms","remote":"127.0.0.1:55462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:39:22.967736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.606406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:39:22.967778Z","caller":"traceutil/trace.go:171","msg":"trace[28305344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:70; }","duration":"966.67382ms","start":"2026-04-17T18:39:22.001092Z","end":"2026-04-17T18:39:22.967765Z","steps":["trace[28305344] 'agreement among raft nodes before linearized reading' (duration: 966.618477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:22.967843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:22.001072Z","time spent":"966.756904ms","remote":"127.0.0.1:55102","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:22.968044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.199129ms","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:39:22.968205Z","caller":"traceutil/trace.go:171","msg":"trace[1951582110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:70; }","duration":"368.45038ms","start":"2026-04-17T18:39:22.599716Z","end":"2026-04-17T18:39:22.968166Z","steps":["trace[1951582110] 'agreement among raft nodes before linearized reading' (duration: 368.042732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:22.968291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:22.599689Z","time spent":"368.586037ms","remote":"127.0.0.1:55384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:39:22.968841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.835172ms","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:39:22.968943Z","caller":"traceutil/trace.go:171","msg":"trace[1745061967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:70; }","duration":"831.974628ms","start":"2026-04-17T18:39:22.136943Z","end":"2026-04-17T18:39:22.968918Z","steps":["trace[1745061967] 'agreement among raft nodes before linearized reading' (duration: 830.721499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:22.969016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:22.136921Z","time spent":"832.083613ms","remote":"127.0.0.1:55384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:39:22.968055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.402605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3489"} {"level":"info","ts":"2026-04-17T18:39:22.969495Z","caller":"traceutil/trace.go:171","msg":"trace[1479833728] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:70; }","duration":"994.730017ms","start":"2026-04-17T18:39:21.974409Z","end":"2026-04-17T18:39:22.969139Z","steps":["trace[1479833728] 'agreement among raft nodes before linearized reading' (duration: 993.250528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:22.969572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:21.974388Z","time spent":"995.163906ms","remote":"127.0.0.1:55276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3512,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-17T18:39:23.475164Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12906082453679973854,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:39:23.975897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12906082453679973854,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:39:24.476661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12906082453679973854,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:39:24.967237Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.994226541s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T18:39:24.971609Z","caller":"traceutil/trace.go:171","msg":"trace[1178486748] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"1.998697883s","start":"2026-04-17T18:39:22.972877Z","end":"2026-04-17T18:39:24.971575Z","steps":["trace[1178486748] 'process raft request' (duration: 1.994805987s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:24.971768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:22.972856Z","time spent":"1.998812988s","remote":"127.0.0.1:55462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:39:25.002039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000151913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T18:39:25.00216Z","caller":"traceutil/trace.go:171","msg":"trace[440395021] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000336443s","start":"2026-04-17T18:39:23.001806Z","end":"2026-04-17T18:39:25.002142Z","steps":["trace[440395021] 'agreement among raft nodes before linearized reading' (duration: 2.000189306s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:25.002206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:23.00178Z","time spent":"2.000416798s","remote":"127.0.0.1:55102","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:39:25 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-17T18:39:25.02128Z","caller":"traceutil/trace.go:171","msg":"trace[2065539483] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:74; }","duration":"2.04635665s","start":"2026-04-17T18:39:22.974825Z","end":"2026-04-17T18:39:25.021182Z","steps":["trace[2065539483] 'read index received' (duration: 1.992796224s)","trace[2065539483] 'applied index is now lower than readState.Index' (duration: 53.559316ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:25.021374Z","caller":"traceutil/trace.go:171","msg":"trace[1832467041] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"2.047433689s","start":"2026-04-17T18:39:22.973885Z","end":"2026-04-17T18:39:25.021319Z","steps":["trace[1832467041] 'process raft request' (duration: 2.047064613s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:25.02141Z","caller":"traceutil/trace.go:171","msg":"trace[1476709481] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"2.047162156s","start":"2026-04-17T18:39:22.974208Z","end":"2026-04-17T18:39:25.02137Z","steps":["trace[1476709481] 'process raft request' (duration: 2.046895014s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:25.021435Z","caller":"traceutil/trace.go:171","msg":"trace[1132701733] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"2.045773403s","start":"2026-04-17T18:39:22.975615Z","end":"2026-04-17T18:39:25.021388Z","steps":["trace[1132701733] 'process raft request' (duration: 2.045535702s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:25.021547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.046697424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T18:39:25.021584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:22.974192Z","time spent":"2.047315453s","remote":"127.0.0.1:55474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:39:25.021592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:22.973862Z","time spent":"2.047640138s","remote":"127.0.0.1:55384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:39:25.021642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:22.975591Z","time spent":"2.045956271s","remote":"127.0.0.1:55384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:25.021619Z","caller":"traceutil/trace.go:171","msg":"trace[1405465891] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:74; }","duration":"2.04681846s","start":"2026-04-17T18:39:22.974785Z","end":"2026-04-17T18:39:25.021603Z","steps":["trace[1405465891] 'agreement among raft nodes before linearized reading' (duration: 2.04658756s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:25.021721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:22.974772Z","time spent":"2.046941396s","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-04-17T18:39:25.021498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.898370886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:39:25.021848Z","caller":"traceutil/trace.go:171","msg":"trace[2067355755] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:74; }","duration":"1.898762483s","start":"2026-04-17T18:39:23.123065Z","end":"2026-04-17T18:39:25.021827Z","steps":["trace[2067355755] 'agreement among raft nodes before linearized reading' (duration: 1.898337113s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:25.0219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:23.123038Z","time spent":"1.898844507s","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-04-17T18:39:25.021914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.244238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:39:25.022143Z","caller":"traceutil/trace.go:171","msg":"trace[40640388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"756.701408ms","start":"2026-04-17T18:39:24.265393Z","end":"2026-04-17T18:39:25.022094Z","steps":["trace[40640388] 'agreement among raft nodes before linearized reading' (duration: 756.159653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:25.022347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:24.265363Z","time spent":"756.953919ms","remote":"127.0.0.1:55102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T18:39:25.287897Z","caller":"traceutil/trace.go:171","msg":"trace[1016560225] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:78; }","duration":"260.827579ms","start":"2026-04-17T18:39:25.027029Z","end":"2026-04-17T18:39:25.287857Z","steps":["trace[1016560225] 'read index received' (duration: 185.127271ms)","trace[1016560225] 'applied index is now lower than readState.Index' (duration: 75.698788ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:25.288069Z","caller":"traceutil/trace.go:171","msg":"trace[899047301] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"262.498592ms","start":"2026-04-17T18:39:25.025556Z","end":"2026-04-17T18:39:25.288054Z","steps":["trace[899047301] 'process raft request' (duration: 186.734672ms)","trace[899047301] 'compare' (duration: 75.362772ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:25.288324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.759295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-17T18:39:25.288421Z","caller":"traceutil/trace.go:171","msg":"trace[1247784027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:75; }","duration":"260.910342ms","start":"2026-04-17T18:39:25.027486Z","end":"2026-04-17T18:39:25.288397Z","steps":["trace[1247784027] 'agreement among raft nodes before linearized reading' (duration: 260.630869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:25.288452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.365173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:39:25.288621Z","caller":"traceutil/trace.go:171","msg":"trace[672729425] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:75; }","duration":"261.652945ms","start":"2026-04-17T18:39:25.026887Z","end":"2026-04-17T18:39:25.28854Z","steps":["trace[672729425] 'agreement among raft nodes before linearized reading' (duration: 261.373263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:25.288692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.466592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T18:39:25.288816Z","caller":"traceutil/trace.go:171","msg":"trace[1251740536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:75; }","duration":"260.64907ms","start":"2026-04-17T18:39:25.028135Z","end":"2026-04-17T18:39:25.288784Z","steps":["trace[1251740536] 'agreement among raft nodes before linearized reading' (duration: 260.347966ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:25.543527Z","caller":"traceutil/trace.go:171","msg":"trace[2014452684] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"172.352504ms","start":"2026-04-17T18:39:25.371153Z","end":"2026-04-17T18:39:25.543506Z","steps":["trace[2014452684] 'process raft request' (duration: 172.308412ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:25.543643Z","caller":"traceutil/trace.go:171","msg":"trace[1415185756] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"174.552653ms","start":"2026-04-17T18:39:25.368989Z","end":"2026-04-17T18:39:25.543542Z","steps":["trace[1415185756] 'process raft request' (duration: 106.672172ms)","trace[1415185756] 'compare' (duration: 67.6388ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:25.74375Z","caller":"traceutil/trace.go:171","msg":"trace[208897669] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"134.305637ms","start":"2026-04-17T18:39:25.609405Z","end":"2026-04-17T18:39:25.74371Z","steps":["trace[208897669] 'process raft request' (duration: 48.989064ms)","trace[208897669] 'compare' (duration: 85.211489ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:25.969559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.007033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-17T18:39:25.969886Z","caller":"traceutil/trace.go:171","msg":"trace[268947877] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"220.242979ms","start":"2026-04-17T18:39:25.749599Z","end":"2026-04-17T18:39:25.969842Z","steps":["trace[268947877] 'process raft request' (duration: 101.752134ms)","trace[268947877] 'compare' (duration: 117.853637ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:26.521546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.937108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-17T18:39:26.521687Z","caller":"traceutil/trace.go:171","msg":"trace[1281856659] linearizableReadLoop","detail":"{readStateIndex:90; appliedIndex:89; }","duration":"419.640815ms","start":"2026-04-17T18:39:26.102026Z","end":"2026-04-17T18:39:26.521666Z","steps":["trace[1281856659] 'read index received' (duration: 130.340231ms)","trace[1281856659] 'applied index is now lower than readState.Index' (duration: 289.298744ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:26.521763Z","caller":"traceutil/trace.go:171","msg":"trace[1216744541] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"544.742408ms","start":"2026-04-17T18:39:25.976976Z","end":"2026-04-17T18:39:26.521718Z","steps":["trace[1216744541] 'process raft request' (duration: 255.529628ms)","trace[1216744541] 'compare' (duration: 288.7386ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:26.521859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.837314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:39:26.521902Z","caller":"traceutil/trace.go:171","msg":"trace[1608711656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:86; }","duration":"419.939168ms","start":"2026-04-17T18:39:26.101949Z","end":"2026-04-17T18:39:26.521889Z","steps":["trace[1608711656] 'agreement among raft nodes before linearized reading' (duration: 419.812692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:26.521946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:26.101906Z","time spent":"420.029782ms","remote":"127.0.0.1:55118","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.521967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:25.976952Z","time spent":"544.911426ms","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":710,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:27.506481Z","caller":"traceutil/trace.go:171","msg":"trace[283847838] transaction","detail":"{read_only:false; response_revision:147; number_of_response:1; }","duration":"101.089615ms","start":"2026-04-17T18:39:27.405349Z","end":"2026-04-17T18:39:27.506438Z","steps":["trace[283847838] 'process raft request' (duration: 69.195275ms)","trace[283847838] 'compare' (duration: 31.695442ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:28.064256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.003887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:39:28.06437Z","caller":"traceutil/trace.go:171","msg":"trace[377753257] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"366.988589ms","start":"2026-04-17T18:39:27.697363Z","end":"2026-04-17T18:39:28.064351Z","steps":["trace[377753257] 'process raft request' (duration: 70.819154ms)","trace[377753257] 'compare' (duration: 295.875442ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:28.064429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:27.697348Z","time spent":"367.059871ms","remote":"127.0.0.1:55460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:28.072866Z","caller":"traceutil/trace.go:171","msg":"trace[236879788] linearizableReadLoop","detail":"{readStateIndex:175; appliedIndex:173; }","duration":"371.639336ms","start":"2026-04-17T18:39:27.701202Z","end":"2026-04-17T18:39:28.072841Z","steps":["trace[236879788] 'read index received' (duration: 67.00829ms)","trace[236879788] 'applied index is now lower than readState.Index' (duration: 304.629636ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:28.072935Z","caller":"traceutil/trace.go:171","msg":"trace[1896181342] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"374.428027ms","start":"2026-04-17T18:39:27.698459Z","end":"2026-04-17T18:39:28.072887Z","steps":["trace[1896181342] 'process raft request' (duration: 374.236899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:28.073112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.870215ms","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:28.07318Z","caller":"traceutil/trace.go:171","msg":"trace[1940184144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:170; }","duration":"371.98194ms","start":"2026-04-17T18:39:27.70118Z","end":"2026-04-17T18:39:28.073162Z","steps":["trace[1940184144] 'agreement among raft nodes before linearized reading' (duration: 371.802493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:28.073218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:27.70117Z","time spent":"372.038862ms","remote":"127.0.0.1:55102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:39:28.073432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:27.698441Z","time spent":"374.610834ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:39:28.089762Z","caller":"traceutil/trace.go:171","msg":"trace[49258919] transaction","detail":"{read_only:false; response_revision:171; number_of_response:1; }","duration":"280.088385ms","start":"2026-04-17T18:39:27.80965Z","end":"2026-04-17T18:39:28.089738Z","steps":["trace[49258919] 'process raft request' (duration: 279.873246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:28.554883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.315661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:39:28.555197Z","caller":"traceutil/trace.go:171","msg":"trace[1379299178] linearizableReadLoop","detail":"{readStateIndex:185; appliedIndex:184; }","duration":"252.670641ms","start":"2026-04-17T18:39:28.302508Z","end":"2026-04-17T18:39:28.555178Z","steps":["trace[1379299178] 'read index received' (duration: 70.920771ms)","trace[1379299178] 'applied index is now lower than readState.Index' (duration: 181.74851ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:28.555282Z","caller":"traceutil/trace.go:171","msg":"trace[67544196] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"253.172974ms","start":"2026-04-17T18:39:28.30207Z","end":"2026-04-17T18:39:28.555243Z","steps":["trace[67544196] 'process raft request' (duration: 71.403403ms)","trace[67544196] 'compare' (duration: 181.204167ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:28.555468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.957003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:39:28.555524Z","caller":"traceutil/trace.go:171","msg":"trace[1467908707] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-scheduler; range_end:; response_count:0; response_revision:180; }","duration":"253.054008ms","start":"2026-04-17T18:39:28.302455Z","end":"2026-04-17T18:39:28.555509Z","steps":["trace[1467908707] 'agreement among raft nodes before linearized reading' (duration: 252.86673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:29.229806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.833768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:39:29.229997Z","caller":"traceutil/trace.go:171","msg":"trace[877947745] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"338.345497ms","start":"2026-04-17T18:39:28.891634Z","end":"2026-04-17T18:39:29.22998Z","steps":["trace[877947745] 'process raft request' (duration: 338.284214ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:29.230211Z","caller":"traceutil/trace.go:171","msg":"trace[2085886279] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"338.87955ms","start":"2026-04-17T18:39:28.891284Z","end":"2026-04-17T18:39:29.230163Z","steps":["trace[2085886279] 'process raft request' (duration: 121.615894ms)","trace[2085886279] 'compare' (duration: 216.721713ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:29.23026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:28.89161Z","time spent":"338.42734ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:39:29.230446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:28.891264Z","time spent":"339.064848ms","remote":"127.0.0.1:55460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:29.40762Z","caller":"traceutil/trace.go:171","msg":"trace[1519086311] linearizableReadLoop","detail":"{readStateIndex:205; appliedIndex:204; }","duration":"127.753766ms","start":"2026-04-17T18:39:29.279804Z","end":"2026-04-17T18:39:29.407558Z","steps":["trace[1519086311] 'read index received' (duration: 107.675618ms)","trace[1519086311] 'applied index is now lower than readState.Index' (duration: 20.073708ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:29.407648Z","caller":"traceutil/trace.go:171","msg":"trace[1175835420] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"136.526106ms","start":"2026-04-17T18:39:29.271068Z","end":"2026-04-17T18:39:29.407594Z","steps":["trace[1175835420] 'process raft request' (duration: 116.406187ms)","trace[1175835420] 'compare' (duration: 19.647399ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:29.407893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.057068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:39:29.407962Z","caller":"traceutil/trace.go:171","msg":"trace[460742154] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:disruption-controller; range_end:; response_count:0; response_revision:200; }","duration":"128.182144ms","start":"2026-04-17T18:39:29.279762Z","end":"2026-04-17T18:39:29.407944Z","steps":["trace[460742154] 'agreement among raft nodes before linearized reading' (duration: 127.975495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:29.408259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.650813ms","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:29.408714Z","caller":"traceutil/trace.go:171","msg":"trace[490880360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:200; }","duration":"107.135634ms","start":"2026-04-17T18:39:29.301547Z","end":"2026-04-17T18:39:29.408683Z","steps":["trace[490880360] 'agreement among raft nodes before linearized reading' (duration: 106.602732ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:30.914295Z","caller":"traceutil/trace.go:171","msg":"trace[804473356] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:281; }","duration":"110.632536ms","start":"2026-04-17T18:39:30.803636Z","end":"2026-04-17T18:39:30.914269Z","steps":["trace[804473356] 'read index received' (duration: 81.948149ms)","trace[804473356] 'applied index is now lower than readState.Index' (duration: 28.683257ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:30.914474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.828374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3472"} {"level":"info","ts":"2026-04-17T18:39:30.914517Z","caller":"traceutil/trace.go:171","msg":"trace[1020589123] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:276; }","duration":"110.944729ms","start":"2026-04-17T18:39:30.803562Z","end":"2026-04-17T18:39:30.914507Z","steps":["trace[1020589123] 'agreement among raft nodes before linearized reading' (duration: 110.813103ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:30.914562Z","caller":"traceutil/trace.go:171","msg":"trace[1491684036] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"167.04041ms","start":"2026-04-17T18:39:30.747489Z","end":"2026-04-17T18:39:30.91453Z","steps":["trace[1491684036] 'process raft request' (duration: 138.03719ms)","trace[1491684036] 'compare' (duration: 28.593553ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:31.596429Z","caller":"traceutil/trace.go:171","msg":"trace[1856743405] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"165.564645ms","start":"2026-04-17T18:39:31.430832Z","end":"2026-04-17T18:39:31.596397Z","steps":["trace[1856743405] 'read index received' (duration: 165.406549ms)","trace[1856743405] 'applied index is now lower than readState.Index' (duration: 156.726µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:31.59653Z","caller":"traceutil/trace.go:171","msg":"trace[1533819696] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"198.308844ms","start":"2026-04-17T18:39:31.398177Z","end":"2026-04-17T18:39:31.596486Z","steps":["trace[1533819696] 'process raft request' (duration: 198.032562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:31.596674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.811786ms","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:31.596724Z","caller":"traceutil/trace.go:171","msg":"trace[269904778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:285; }","duration":"165.92108ms","start":"2026-04-17T18:39:31.430791Z","end":"2026-04-17T18:39:31.596712Z","steps":["trace[269904778] 'agreement among raft nodes before linearized reading' (duration: 165.770203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:31.596874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.997664ms","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:31.59696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.116181ms","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:31.597004Z","caller":"traceutil/trace.go:171","msg":"trace[69832584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:285; }","duration":"166.16432ms","start":"2026-04-17T18:39:31.430812Z","end":"2026-04-17T18:39:31.596976Z","steps":["trace[69832584] 'agreement among raft nodes before linearized reading' (duration: 165.93604ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:31.597121Z","caller":"traceutil/trace.go:171","msg":"trace[75889217] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:285; }","duration":"127.238025ms","start":"2026-04-17T18:39:31.469776Z","end":"2026-04-17T18:39:31.597014Z","steps":["trace[75889217] 'agreement among raft nodes before linearized reading' (duration: 127.062378ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:31.793199Z","caller":"traceutil/trace.go:171","msg":"trace[312675737] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"105.687347ms","start":"2026-04-17T18:39:31.687466Z","end":"2026-04-17T18:39:31.793153Z","steps":["trace[312675737] 'process raft request' (duration: 81.100983ms)","trace[312675737] 'compare' (duration: 24.321523ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:32.114683Z","caller":"traceutil/trace.go:171","msg":"trace[1995495068] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:303; }","duration":"153.597635ms","start":"2026-04-17T18:39:31.961046Z","end":"2026-04-17T18:39:32.114644Z","steps":["trace[1995495068] 'read index received' (duration: 171.417µs)","trace[1995495068] 'applied index is now lower than readState.Index' (duration: 153.424858ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:32.114938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.816695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:39:32.114995Z","caller":"traceutil/trace.go:171","msg":"trace[603313485] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:298; }","duration":"153.982312ms","start":"2026-04-17T18:39:31.960996Z","end":"2026-04-17T18:39:32.114978Z","steps":["trace[603313485] 'agreement among raft nodes before linearized reading' (duration: 153.785343ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:32.115103Z","caller":"traceutil/trace.go:171","msg":"trace[253884051] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"154.023333ms","start":"2026-04-17T18:39:31.961017Z","end":"2026-04-17T18:39:32.115041Z","steps":["trace[253884051] 'process raft request' (duration: 118.268627ms)","trace[253884051] 'compare' (duration: 35.151201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:33.823336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.36973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-17T18:39:33.823527Z","caller":"traceutil/trace.go:171","msg":"trace[882332874] linearizableReadLoop","detail":"{readStateIndex:343; appliedIndex:342; }","duration":"198.116552ms","start":"2026-04-17T18:39:33.625388Z","end":"2026-04-17T18:39:33.823504Z","steps":["trace[882332874] 'read index received' (duration: 4.661127ms)","trace[882332874] 'applied index is now lower than readState.Index' (duration: 193.454265ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:33.823615Z","caller":"traceutil/trace.go:171","msg":"trace[2011993635] transaction","detail":"{read_only:false; number_of_response:0; response_revision:331; }","duration":"198.32012ms","start":"2026-04-17T18:39:33.625255Z","end":"2026-04-17T18:39:33.823575Z","steps":["trace[2011993635] 'process raft request' (duration: 89.618716ms)","trace[2011993635] 'compare' (duration: 108.305198ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:33.82376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.971886ms","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:33.823802Z","caller":"traceutil/trace.go:171","msg":"trace[1889264567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:331; }","duration":"196.07663ms","start":"2026-04-17T18:39:33.627713Z","end":"2026-04-17T18:39:33.82379Z","steps":["trace[1889264567] 'agreement among raft nodes before linearized reading' (duration: 195.931033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:33.8238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.377473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4316"} {"level":"info","ts":"2026-04-17T18:39:33.823942Z","caller":"traceutil/trace.go:171","msg":"trace[1562842259] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:331; }","duration":"198.620203ms","start":"2026-04-17T18:39:33.625294Z","end":"2026-04-17T18:39:33.823914Z","steps":["trace[1562842259] 'agreement among raft nodes before linearized reading' (duration: 198.30916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:33.823945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.680168ms","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:33.824061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.150937ms","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:33.824073Z","caller":"traceutil/trace.go:171","msg":"trace[110443277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:331; }","duration":"170.864586ms","start":"2026-04-17T18:39:33.653163Z","end":"2026-04-17T18:39:33.824028Z","steps":["trace[110443277] 'agreement among raft nodes before linearized reading' (duration: 170.627296ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:33.824094Z","caller":"traceutil/trace.go:171","msg":"trace[1979413373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:331; }","duration":"171.206339ms","start":"2026-04-17T18:39:33.652878Z","end":"2026-04-17T18:39:33.824085Z","steps":["trace[1979413373] 'agreement among raft nodes before linearized reading' (duration: 171.143806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:33.824118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.802897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-17T18:39:33.824167Z","caller":"traceutil/trace.go:171","msg":"trace[901781143] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:331; }","duration":"186.912692ms","start":"2026-04-17T18:39:33.637239Z","end":"2026-04-17T18:39:33.824151Z","steps":["trace[901781143] 'agreement among raft nodes before linearized reading' (duration: 186.794747ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:33.824906Z","caller":"traceutil/trace.go:171","msg":"trace[1658340124] transaction","detail":"{read_only:false; number_of_response:0; response_revision:331; }","duration":"198.955086ms","start":"2026-04-17T18:39:33.625931Z","end":"2026-04-17T18:39:33.824886Z","steps":["trace[1658340124] 'process raft request' (duration: 197.521508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:35.137056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.095761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:39:35.137231Z","caller":"traceutil/trace.go:171","msg":"trace[1498454698] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"212.090719ms","start":"2026-04-17T18:39:34.925107Z","end":"2026-04-17T18:39:35.137198Z","steps":["trace[1498454698] 'process raft request' (duration: 45.749899ms)","trace[1498454698] 'compare' (duration: 165.902033ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:35.154817Z","caller":"traceutil/trace.go:171","msg":"trace[2038456240] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:363; }","duration":"229.40183ms","start":"2026-04-17T18:39:34.92539Z","end":"2026-04-17T18:39:35.154792Z","steps":["trace[2038456240] 'read index received' (duration: 45.549681ms)","trace[2038456240] 'applied index is now lower than readState.Index' (duration: 183.851379ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:35.155159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.771824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-04-17T18:39:35.15516Z","caller":"traceutil/trace.go:171","msg":"trace[273757623] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"229.873369ms","start":"2026-04-17T18:39:34.92522Z","end":"2026-04-17T18:39:35.155093Z","steps":["trace[273757623] 'process raft request' (duration: 229.436062ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:35.155236Z","caller":"traceutil/trace.go:171","msg":"trace[1748922275] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:352; }","duration":"229.880779ms","start":"2026-04-17T18:39:34.925326Z","end":"2026-04-17T18:39:35.155207Z","steps":["trace[1748922275] 'agreement among raft nodes before linearized reading' (duration: 229.746174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:35.155425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.980197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-17T18:39:35.155536Z","caller":"traceutil/trace.go:171","msg":"trace[631447756] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:352; }","duration":"208.173835ms","start":"2026-04-17T18:39:34.947341Z","end":"2026-04-17T18:39:35.155515Z","steps":["trace[631447756] 'agreement among raft nodes before linearized reading' (duration: 207.958607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:35.15562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.708811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-17T18:39:35.155733Z","caller":"traceutil/trace.go:171","msg":"trace[1457248901] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:352; }","duration":"108.840787ms","start":"2026-04-17T18:39:35.04682Z","end":"2026-04-17T18:39:35.15566Z","steps":["trace[1457248901] 'agreement among raft nodes before linearized reading' (duration: 108.67837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:35.155779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.869825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-17T18:39:35.155873Z","caller":"traceutil/trace.go:171","msg":"trace[1734816537] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:352; }","duration":"158.103425ms","start":"2026-04-17T18:39:34.997756Z","end":"2026-04-17T18:39:35.15586Z","steps":["trace[1734816537] 'agreement among raft nodes before linearized reading' (duration: 157.883556ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:35.341089Z","caller":"traceutil/trace.go:171","msg":"trace[1654311643] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"117.918053ms","start":"2026-04-17T18:39:35.223121Z","end":"2026-04-17T18:39:35.341039Z","steps":["trace[1654311643] 'process raft request' (duration: 32.174725ms)","trace[1654311643] 'compare' (duration: 85.364443ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:35.342929Z","caller":"traceutil/trace.go:171","msg":"trace[823914940] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"115.31368ms","start":"2026-04-17T18:39:35.227575Z","end":"2026-04-17T18:39:35.342889Z","steps":["trace[823914940] 'process raft request' (duration: 114.867293ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:35.554815Z","caller":"traceutil/trace.go:171","msg":"trace[717108890] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:374; }","duration":"157.523789ms","start":"2026-04-17T18:39:35.397251Z","end":"2026-04-17T18:39:35.554775Z","steps":["trace[717108890] 'read index received' (duration: 78.341278ms)","trace[717108890] 'applied index is now lower than readState.Index' (duration: 79.181061ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:35.555042Z","caller":"traceutil/trace.go:171","msg":"trace[39776736] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"171.686696ms","start":"2026-04-17T18:39:35.383336Z","end":"2026-04-17T18:39:35.555023Z","steps":["trace[39776736] 'process raft request' (duration: 92.160501ms)","trace[39776736] 'compare' (duration: 79.088987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:35.555206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.507245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-17T18:39:35.555295Z","caller":"traceutil/trace.go:171","msg":"trace[1545470693] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:362; }","duration":"107.64774ms","start":"2026-04-17T18:39:35.447608Z","end":"2026-04-17T18:39:35.555255Z","steps":["trace[1545470693] 'agreement among raft nodes before linearized reading' (duration: 107.447563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:35.555336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.072481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-17T18:39:35.555391Z","caller":"traceutil/trace.go:171","msg":"trace[1094241956] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:362; }","duration":"158.180446ms","start":"2026-04-17T18:39:35.397195Z","end":"2026-04-17T18:39:35.555375Z","steps":["trace[1094241956] 'agreement among raft nodes before linearized reading' (duration: 158.077702ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:50.191385Z","caller":"traceutil/trace.go:171","msg":"trace[408614894] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"266.501416ms","start":"2026-04-17T18:39:49.924859Z","end":"2026-04-17T18:39:50.19136Z","steps":["trace[408614894] 'process raft request' (duration: 266.33133ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:50.229921Z","caller":"traceutil/trace.go:171","msg":"trace[446576111] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:485; }","duration":"301.069068ms","start":"2026-04-17T18:39:49.92883Z","end":"2026-04-17T18:39:50.229899Z","steps":["trace[446576111] 'read index received' (duration: 262.41827ms)","trace[446576111] 'applied index is now lower than readState.Index' (duration: 38.649878ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:39:50.23003Z","caller":"traceutil/trace.go:171","msg":"trace[650758596] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"302.986941ms","start":"2026-04-17T18:39:49.927035Z","end":"2026-04-17T18:39:50.230022Z","steps":["trace[650758596] 'process raft request' (duration: 302.748423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:50.23013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:49.927017Z","time spent":"303.033743ms","remote":"127.0.0.1:55384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:39:50.230661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.775381ms","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:50.230832Z","caller":"traceutil/trace.go:171","msg":"trace[137277501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:469; }","duration":"301.988427ms","start":"2026-04-17T18:39:49.928811Z","end":"2026-04-17T18:39:50.230799Z","steps":["trace[137277501] 'agreement among raft nodes before linearized reading' (duration: 301.655886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:50.230972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:49.928803Z","time spent":"302.153023ms","remote":"127.0.0.1:55384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T18:39:54.356026Z","caller":"traceutil/trace.go:171","msg":"trace[2054557858] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"104.858856ms","start":"2026-04-17T18:39:54.251139Z","end":"2026-04-17T18:39:54.355998Z","steps":["trace[2054557858] 'process raft request' (duration: 104.698791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:58.731459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12906082453679974774,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T18:39:58.744167Z","caller":"traceutil/trace.go:171","msg":"trace[1543341590] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"524.727295ms","start":"2026-04-17T18:39:58.219413Z","end":"2026-04-17T18:39:58.744141Z","steps":["trace[1543341590] 'process raft request' (duration: 524.554449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:58.74491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.219385Z","time spent":"525.445376ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:58.757617Z","caller":"traceutil/trace.go:171","msg":"trace[1474157161] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"537.75662ms","start":"2026-04-17T18:39:58.219826Z","end":"2026-04-17T18:39:58.757582Z","steps":["trace[1474157161] 'process raft request' (duration: 537.538563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:58.757809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.219814Z","time spent":"537.903374ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:58.757697Z","caller":"traceutil/trace.go:171","msg":"trace[685280749] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"537.692258ms","start":"2026-04-17T18:39:58.219984Z","end":"2026-04-17T18:39:58.757676Z","steps":["trace[685280749] 'process raft request' (duration: 537.477981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:58.758327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.219978Z","time spent":"538.286015ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:58.758499Z","caller":"traceutil/trace.go:171","msg":"trace[506469432] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"528.342664ms","start":"2026-04-17T18:39:58.230124Z","end":"2026-04-17T18:39:58.758466Z","steps":["trace[506469432] 'process raft request' (duration: 527.384806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:58.758651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.2301Z","time spent":"528.476398ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:58.758736Z","caller":"traceutil/trace.go:171","msg":"trace[1567442882] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"528.434976ms","start":"2026-04-17T18:39:58.23024Z","end":"2026-04-17T18:39:58.758675Z","steps":["trace[1567442882] 'process raft request' (duration: 528.180389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:58.758885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.230234Z","time spent":"528.57274ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:58.75896Z","caller":"traceutil/trace.go:171","msg":"trace[689806509] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"511.565046ms","start":"2026-04-17T18:39:58.247383Z","end":"2026-04-17T18:39:58.758948Z","steps":["trace[689806509] 'process raft request' (duration: 511.186734ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:58.758975Z","caller":"traceutil/trace.go:171","msg":"trace[496559261] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:540; }","duration":"528.578621ms","start":"2026-04-17T18:39:58.230376Z","end":"2026-04-17T18:39:58.758954Z","steps":["trace[496559261] 'read index received' (duration: 513.546286ms)","trace[496559261] 'applied index is now lower than readState.Index' (duration: 15.030805ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:39:58.759004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.247355Z","time spent":"511.629268ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:58.75906Z","caller":"traceutil/trace.go:171","msg":"trace[1731872643] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"491.305633ms","start":"2026-04-17T18:39:58.26774Z","end":"2026-04-17T18:39:58.759046Z","steps":["trace[1731872643] 'process raft request' (duration: 490.89937ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:58.759095Z","caller":"traceutil/trace.go:171","msg":"trace[75226401] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"525.917082ms","start":"2026-04-17T18:39:58.233172Z","end":"2026-04-17T18:39:58.759089Z","steps":["trace[75226401] 'process raft request' (duration: 525.350434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:58.759128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.233158Z","time spent":"525.954653ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:39:58.759124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.267713Z","time spent":"491.376636ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:58.759209Z","caller":"traceutil/trace.go:171","msg":"trace[1258816269] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"354.047499ms","start":"2026-04-17T18:39:58.405147Z","end":"2026-04-17T18:39:58.759195Z","steps":["trace[1258816269] 'process raft request' (duration: 353.690357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:58.759246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.405105Z","time spent":"354.126551ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:39:58.759288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.899091ms","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:58.759307Z","caller":"traceutil/trace.go:171","msg":"trace[1976223796] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"467.475862ms","start":"2026-04-17T18:39:58.291823Z","end":"2026-04-17T18:39:58.759299Z","steps":["trace[1976223796] 'process raft request' (duration: 466.881263ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:58.759308Z","caller":"traceutil/trace.go:171","msg":"trace[854568012] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"529.122468ms","start":"2026-04-17T18:39:58.230171Z","end":"2026-04-17T18:39:58.759294Z","steps":["trace[854568012] 'process raft request' (duration: 528.149898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:58.759367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.291776Z","time spent":"467.552734ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:39:58.759373Z","caller":"traceutil/trace.go:171","msg":"trace[1971187010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"528.972704ms","start":"2026-04-17T18:39:58.230354Z","end":"2026-04-17T18:39:58.759327Z","steps":["trace[1971187010] 'agreement among raft nodes before linearized reading' (duration: 528.84876ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:39:58.759071Z","caller":"traceutil/trace.go:171","msg":"trace[357297467] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"528.716386ms","start":"2026-04-17T18:39:58.230347Z","end":"2026-04-17T18:39:58.759064Z","steps":["trace[357297467] 'process raft request' (duration: 528.127928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:58.759428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.230347Z","time spent":"529.054246ms","remote":"127.0.0.1:55384","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:58.75943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.230156Z","time spent":"529.245422ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:39:58.759443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.230339Z","time spent":"529.086277ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:39:58.765607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.662641ms","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:58.76569Z","caller":"traceutil/trace.go:171","msg":"trace[1988347203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"408.792295ms","start":"2026-04-17T18:39:58.356885Z","end":"2026-04-17T18:39:58.765678Z","steps":["trace[1988347203] 'agreement among raft nodes before linearized reading' (duration: 408.63069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:58.765749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.356868Z","time spent":"408.863188ms","remote":"127.0.0.1:55384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:39:58.76598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.424777ms","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:58.766035Z","caller":"traceutil/trace.go:171","msg":"trace[784825065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"197.512099ms","start":"2026-04-17T18:39:58.568515Z","end":"2026-04-17T18:39:58.766027Z","steps":["trace[784825065] 'agreement among raft nodes before linearized reading' (duration: 197.439757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:58.766213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.014827ms","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:58.766266Z","caller":"traceutil/trace.go:171","msg":"trace[549540935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"390.108809ms","start":"2026-04-17T18:39:58.376148Z","end":"2026-04-17T18:39:58.766257Z","steps":["trace[549540935] 'agreement among raft nodes before linearized reading' (duration: 390.027557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:39:58.766373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:58.376121Z","time spent":"390.242984ms","remote":"127.0.0.1:55384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T18:40:00.705442Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12906082453679974812,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:40:01.029628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299893547s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:40:01.029776Z","caller":"traceutil/trace.go:171","msg":"trace[529818942] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.38280098s","start":"2026-04-17T18:39:59.646952Z","end":"2026-04-17T18:40:01.029753Z","steps":["trace[529818942] 'process raft request' (duration: 82.683506ms)","trace[529818942] 'compare' (duration: 1.299190287s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:40:01.029836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:59.64693Z","time spent":"1.382879972s","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:40:01.065636Z","caller":"traceutil/trace.go:171","msg":"trace[1602910947] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"861.254111ms","start":"2026-04-17T18:40:00.204341Z","end":"2026-04-17T18:40:01.065595Z","steps":["trace[1602910947] 'read index received' (duration: 859.50447ms)","trace[1602910947] 'applied index is now lower than readState.Index' (duration: 1.745951ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:40:01.06563Z","caller":"traceutil/trace.go:171","msg":"trace[510821103] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.333852871s","start":"2026-04-17T18:39:59.731734Z","end":"2026-04-17T18:40:01.065587Z","steps":["trace[510821103] 'process raft request' (duration: 1.332595134s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:01.065877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:39:59.7317Z","time spent":"1.334059347s","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:40:01.065992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.634182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.9\" ","response":"range_response_count:1 size:131"} {"level":"warn","ts":"2026-04-17T18:40:01.065971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.895832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:40:01.066079Z","caller":"traceutil/trace.go:171","msg":"trace[1123772157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"394.069068ms","start":"2026-04-17T18:40:00.671985Z","end":"2026-04-17T18:40:01.066054Z","steps":["trace[1123772157] 'agreement among raft nodes before linearized reading' (duration: 393.873062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:01.066047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.169308ms","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:40:01.066144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:40:00.67196Z","time spent":"394.17561ms","remote":"127.0.0.1:55102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T18:40:01.06621Z","caller":"traceutil/trace.go:171","msg":"trace[1257704377] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"263.350893ms","start":"2026-04-17T18:40:00.802822Z","end":"2026-04-17T18:40:01.066173Z","steps":["trace[1257704377] 'agreement among raft nodes before linearized reading' (duration: 263.066275ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:40:01.066025Z","caller":"traceutil/trace.go:171","msg":"trace[552302244] range","detail":"{range_begin:/registry/masterleases/199.19.213.9; range_end:; response_count:1; response_revision:594; }","duration":"861.735905ms","start":"2026-04-17T18:40:00.204275Z","end":"2026-04-17T18:40:01.066011Z","steps":["trace[552302244] 'agreement among raft nodes before linearized reading' (duration: 861.438617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:01.066317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.666513ms","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:40:01.066337Z","caller":"traceutil/trace.go:171","msg":"trace[1230984978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"263.686684ms","start":"2026-04-17T18:40:00.802645Z","end":"2026-04-17T18:40:01.066332Z","steps":["trace[1230984978] 'agreement among raft nodes before linearized reading' (duration: 263.602301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:01.066356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:40:00.204248Z","time spent":"862.083636ms","remote":"127.0.0.1:55132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.19.213.9\" "} {"level":"warn","ts":"2026-04-17T18:40:01.066446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.701894ms","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:40:01.06646Z","caller":"traceutil/trace.go:171","msg":"trace[675312223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"263.780256ms","start":"2026-04-17T18:40:00.802676Z","end":"2026-04-17T18:40:01.066457Z","steps":["trace[675312223] 'agreement among raft nodes before linearized reading' (duration: 263.745365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:01.066533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.955731ms","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:40:01.066544Z","caller":"traceutil/trace.go:171","msg":"trace[855396059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"262.981113ms","start":"2026-04-17T18:40:00.80356Z","end":"2026-04-17T18:40:01.066541Z","steps":["trace[855396059] 'agreement among raft nodes before linearized reading' (duration: 262.939732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:05.603467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.464376ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T18:40:05.603597Z","caller":"traceutil/trace.go:171","msg":"trace[1663315516] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"210.547139ms","start":"2026-04-17T18:40:05.393009Z","end":"2026-04-17T18:40:05.603557Z","steps":["trace[1663315516] 'read index received' (duration: 11.892244ms)","trace[1663315516] 'applied index is now lower than readState.Index' (duration: 198.653815ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:40:05.60384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.259534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:40:05.603825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.763545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:40:05.603938Z","caller":"traceutil/trace.go:171","msg":"trace[86087504] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:626; }","duration":"210.94217ms","start":"2026-04-17T18:40:05.392978Z","end":"2026-04-17T18:40:05.603921Z","steps":["trace[86087504] 'agreement among raft nodes before linearized reading' (duration: 210.728135ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:40:05.603866Z","caller":"traceutil/trace.go:171","msg":"trace[802051932] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:626; }","duration":"209.313226ms","start":"2026-04-17T18:40:05.394546Z","end":"2026-04-17T18:40:05.60386Z","steps":["trace[802051932] 'agreement among raft nodes before linearized reading' (duration: 209.177722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:05.604472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.483956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:40:05.604495Z","caller":"traceutil/trace.go:171","msg":"trace[2109259072] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:626; }","duration":"190.507716ms","start":"2026-04-17T18:40:05.413981Z","end":"2026-04-17T18:40:05.604489Z","steps":["trace[2109259072] 'agreement among raft nodes before linearized reading' (duration: 190.472005ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:40:06.794313Z","caller":"traceutil/trace.go:171","msg":"trace[1508393937] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"120.419831ms","start":"2026-04-17T18:40:06.673864Z","end":"2026-04-17T18:40:06.794284Z","steps":["trace[1508393937] 'read index received' (duration: 119.215927ms)","trace[1508393937] 'applied index is now lower than readState.Index' (duration: 1.202564ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:40:06.794494Z","caller":"traceutil/trace.go:171","msg":"trace[1385156338] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"773.237148ms","start":"2026-04-17T18:40:06.02121Z","end":"2026-04-17T18:40:06.794447Z","steps":["trace[1385156338] 'process raft request' (duration: 772.215048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:06.794611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.686228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:40:06.794656Z","caller":"traceutil/trace.go:171","msg":"trace[339712674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"120.852213ms","start":"2026-04-17T18:40:06.673793Z","end":"2026-04-17T18:40:06.794645Z","steps":["trace[339712674] 'agreement among raft nodes before linearized reading' (duration: 120.711529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:06.794818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:40:06.021182Z","time spent":"773.430623ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:40:06.921951Z","caller":"traceutil/trace.go:171","msg":"trace[1906637312] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"110.667439ms","start":"2026-04-17T18:40:06.811258Z","end":"2026-04-17T18:40:06.921926Z","steps":["trace[1906637312] 'process raft request' (duration: 110.461234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:07.632219Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12906082453679975016,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:40:07.944307Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.022518357s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T18:40:09.303245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.236667ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:40:09.303382Z","caller":"traceutil/trace.go:171","msg":"trace[1599608353] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:631; }","duration":"748.391241ms","start":"2026-04-17T18:40:08.554972Z","end":"2026-04-17T18:40:09.303363Z","steps":["trace[1599608353] 'range keys from in-memory index tree' (duration: 748.216875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:09.30411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359506557s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:40:09.304355Z","caller":"traceutil/trace.go:171","msg":"trace[1567152094] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"2.172992742s","start":"2026-04-17T18:40:07.131291Z","end":"2026-04-17T18:40:09.304283Z","steps":["trace[1567152094] 'read index received' (duration: 813.153736ms)","trace[1567152094] 'applied index is now lower than readState.Index' (duration: 1.359837476s)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:40:09.30438Z","caller":"traceutil/trace.go:171","msg":"trace[569171972] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"2.445201661s","start":"2026-04-17T18:40:06.859143Z","end":"2026-04-17T18:40:09.304345Z","steps":["trace[569171972] 'process raft request' (duration: 1.085384485s)","trace[569171972] 'compare' (duration: 1.35812246s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:40:09.30458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:40:06.859115Z","time spent":"2.445440818s","remote":"127.0.0.1:55460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:40:09.304645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.173341422s","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:40:09.304741Z","caller":"traceutil/trace.go:171","msg":"trace[1125272708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"2.173485966s","start":"2026-04-17T18:40:07.131228Z","end":"2026-04-17T18:40:09.304714Z","steps":["trace[1125272708] 'agreement among raft nodes before linearized reading' (duration: 2.173204718s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:09.304781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:40:07.131195Z","time spent":"2.173577868s","remote":"127.0.0.1:55384","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:40:09.305023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.166278007s","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:40:09.305142Z","caller":"traceutil/trace.go:171","msg":"trace[1336738405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"2.166445551s","start":"2026-04-17T18:40:07.138687Z","end":"2026-04-17T18:40:09.305133Z","steps":["trace[1336738405] 'agreement among raft nodes before linearized reading' (duration: 2.166226845s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:09.305201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:40:07.138641Z","time spent":"2.166552145s","remote":"127.0.0.1:55384","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:40:09.305214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.166137933s","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:40:09.305255Z","caller":"traceutil/trace.go:171","msg":"trace[1957871341] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"2.166193175s","start":"2026-04-17T18:40:07.139049Z","end":"2026-04-17T18:40:09.305242Z","steps":["trace[1957871341] 'agreement among raft nodes before linearized reading' (duration: 2.166112883s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:09.305286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:40:07.139036Z","time spent":"2.166242676s","remote":"127.0.0.1:55384","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:40:09.305276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.164750145s","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:40:09.305329Z","caller":"traceutil/trace.go:171","msg":"trace[2047813549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"2.164827227s","start":"2026-04-17T18:40:07.140488Z","end":"2026-04-17T18:40:09.305316Z","steps":["trace[2047813549] 'agreement among raft nodes before linearized reading' (duration: 2.164611611s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:09.305425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:40:07.140474Z","time spent":"2.16494134s","remote":"127.0.0.1:55384","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:40:09.305041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.6334051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:40:09.305551Z","caller":"traceutil/trace.go:171","msg":"trace[1638801560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"1.633950645s","start":"2026-04-17T18:40:07.671587Z","end":"2026-04-17T18:40:09.305538Z","steps":["trace[1638801560] 'agreement among raft nodes before linearized reading' (duration: 1.633431291s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:09.305589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:40:07.671561Z","time spent":"1.634019807s","remote":"127.0.0.1:55102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T18:40:11.262357Z","caller":"traceutil/trace.go:171","msg":"trace[972818077] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"357.731718ms","start":"2026-04-17T18:40:10.904577Z","end":"2026-04-17T18:40:11.262309Z","steps":["trace[972818077] 'process raft request' (duration: 357.594754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:11.262659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:40:10.904541Z","time spent":"357.937783ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:40:11.26758Z","caller":"traceutil/trace.go:171","msg":"trace[769627433] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"156.955251ms","start":"2026-04-17T18:40:11.110607Z","end":"2026-04-17T18:40:11.267563Z","steps":["trace[769627433] 'process raft request' (duration: 156.870708ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:40:14.132165Z","caller":"traceutil/trace.go:171","msg":"trace[299132709] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"208.387159ms","start":"2026-04-17T18:40:13.923734Z","end":"2026-04-17T18:40:14.132121Z","steps":["trace[299132709] 'process raft request' (duration: 208.052371ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:40:25.360718Z","caller":"traceutil/trace.go:171","msg":"trace[521336054] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"560.694335ms","start":"2026-04-17T18:40:24.799991Z","end":"2026-04-17T18:40:25.360685Z","steps":["trace[521336054] 'process raft request' (duration: 560.540231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:25.361002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:40:24.799968Z","time spent":"560.93646ms","remote":"127.0.0.1:55384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:40:25.368047Z","caller":"traceutil/trace.go:171","msg":"trace[36949641] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"143.671831ms","start":"2026-04-17T18:40:25.22435Z","end":"2026-04-17T18:40:25.368022Z","steps":["trace[36949641] 'read index received' (duration: 136.359722ms)","trace[36949641] 'applied index is now lower than readState.Index' (duration: 7.310809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:40:25.368214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.845045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:40:25.36825Z","caller":"traceutil/trace.go:171","msg":"trace[1794121693] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:795; }","duration":"143.952587ms","start":"2026-04-17T18:40:25.224287Z","end":"2026-04-17T18:40:25.36824Z","steps":["trace[1794121693] 'agreement among raft nodes before linearized reading' (duration: 143.833104ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:40:25.368221Z","caller":"traceutil/trace.go:171","msg":"trace[166445993] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"502.616018ms","start":"2026-04-17T18:40:24.865586Z","end":"2026-04-17T18:40:25.368202Z","steps":["trace[166445993] 'process raft request' (duration: 502.326782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:40:25.368392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:40:24.865554Z","time spent":"502.781901ms","remote":"127.0.0.1:55364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:42:40.462095Z","caller":"traceutil/trace.go:171","msg":"trace[555180895] linearizableReadLoop","detail":"{readStateIndex:1484; appliedIndex:1483; }","duration":"134.187826ms","start":"2026-04-17T18:42:40.32788Z","end":"2026-04-17T18:42:40.462068Z","steps":["trace[555180895] 'read index received' (duration: 81.032169ms)","trace[555180895] 'applied index is now lower than readState.Index' (duration: 53.154577ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:42:40.462301Z","caller":"traceutil/trace.go:171","msg":"trace[1395918445] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"135.428331ms","start":"2026-04-17T18:42:40.326826Z","end":"2026-04-17T18:42:40.462254Z","steps":["trace[1395918445] 'process raft request' (duration: 82.125103ms)","trace[1395918445] 'compare' (duration: 53.009115ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:42:40.462399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.461099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-17T18:42:40.462444Z","caller":"traceutil/trace.go:171","msg":"trace[1627694842] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1421; }","duration":"134.584881ms","start":"2026-04-17T18:42:40.327847Z","end":"2026-04-17T18:42:40.462432Z","steps":["trace[1627694842] 'agreement among raft nodes before linearized reading' (duration: 134.326438ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:42:52.581638Z","caller":"traceutil/trace.go:171","msg":"trace[1078656031] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"155.358771ms","start":"2026-04-17T18:42:52.426248Z","end":"2026-04-17T18:42:52.581607Z","steps":["trace[1078656031] 'process raft request' (duration: 155.161999ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:42:52.587009Z","caller":"traceutil/trace.go:171","msg":"trace[495241259] linearizableReadLoop","detail":"{readStateIndex:1524; appliedIndex:1521; }","duration":"136.988579ms","start":"2026-04-17T18:42:52.450002Z","end":"2026-04-17T18:42:52.586991Z","steps":["trace[495241259] 'read index received' (duration: 131.57054ms)","trace[495241259] 'applied index is now lower than readState.Index' (duration: 5.415709ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:42:52.587125Z","caller":"traceutil/trace.go:171","msg":"trace[1654365447] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"157.17205ms","start":"2026-04-17T18:42:52.42993Z","end":"2026-04-17T18:42:52.587102Z","steps":["trace[1654365447] 'process raft request' (duration: 157.029848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:42:52.587281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.128359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:1 size:232"} {"level":"info","ts":"2026-04-17T18:42:52.587321Z","caller":"traceutil/trace.go:171","msg":"trace[151715199] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:1; response_revision:1459; }","duration":"103.219769ms","start":"2026-04-17T18:42:52.484091Z","end":"2026-04-17T18:42:52.587311Z","steps":["trace[151715199] 'agreement among raft nodes before linearized reading' (duration: 103.145458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:42:52.587701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.701846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:87 size:64627"} {"level":"info","ts":"2026-04-17T18:42:52.58773Z","caller":"traceutil/trace.go:171","msg":"trace[5371284] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:87; response_revision:1459; }","duration":"137.734907ms","start":"2026-04-17T18:42:52.449987Z","end":"2026-04-17T18:42:52.587722Z","steps":["trace[5371284] 'agreement among raft nodes before linearized reading' (duration: 137.11529ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:42:52.587109Z","caller":"traceutil/trace.go:171","msg":"trace[703001193] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"157.220501ms","start":"2026-04-17T18:42:52.429851Z","end":"2026-04-17T18:42:52.587071Z","steps":["trace[703001193] 'process raft request' (duration: 156.989358ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:42:52.842031Z","caller":"traceutil/trace.go:171","msg":"trace[2083987972] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"102.21834ms","start":"2026-04-17T18:42:52.739785Z","end":"2026-04-17T18:42:52.842003Z","steps":["trace[2083987972] 'process raft request' (duration: 101.931607ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:42:58.816631Z","caller":"traceutil/trace.go:171","msg":"trace[726584960] linearizableReadLoop","detail":"{readStateIndex:1544; appliedIndex:1543; }","duration":"123.77598ms","start":"2026-04-17T18:42:58.69282Z","end":"2026-04-17T18:42:58.816596Z","steps":["trace[726584960] 'read index received' (duration: 49.512296ms)","trace[726584960] 'applied index is now lower than readState.Index' (duration: 74.262074ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:42:58.816927Z","caller":"traceutil/trace.go:171","msg":"trace[553042420] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"126.142596ms","start":"2026-04-17T18:42:58.690766Z","end":"2026-04-17T18:42:58.816909Z","steps":["trace[553042420] 'process raft request' (duration: 51.628179ms)","trace[553042420] 'compare' (duration: 74.004441ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:42:58.817133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.551357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qxvwl\" ","response":"range_response_count:1 size:18517"} {"level":"warn","ts":"2026-04-17T18:42:58.817203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.860414ms","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:42:58.817238Z","caller":"traceutil/trace.go:171","msg":"trace[821213484] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qxvwl; range_end:; response_count:1; response_revision:1478; }","duration":"122.709769ms","start":"2026-04-17T18:42:58.694508Z","end":"2026-04-17T18:42:58.817217Z","steps":["trace[821213484] 'agreement among raft nodes before linearized reading' (duration: 122.427696ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:42:58.817278Z","caller":"traceutil/trace.go:171","msg":"trace[1805342232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1478; }","duration":"115.958336ms","start":"2026-04-17T18:42:58.7013Z","end":"2026-04-17T18:42:58.817259Z","steps":["trace[1805342232] 'agreement among raft nodes before linearized reading' (duration: 115.759794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:42:58.817192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.360566ms","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:42:58.817513Z","caller":"traceutil/trace.go:171","msg":"trace[683179175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1478; }","duration":"124.719531ms","start":"2026-04-17T18:42:58.692778Z","end":"2026-04-17T18:42:58.817497Z","steps":["trace[683179175] 'agreement among raft nodes before linearized reading' (duration: 124.276196ms)"],"step_count":1}