{"level":"warn","ts":"2026-02-26T23:40:57.111346Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T23:40:57.111445Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-26T23:40:57.111535Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T23:40:57.111547Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-02-26T23:40:57.111566Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-26T23:40:57.114137Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-02-26T23:40:57.114246Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-26T23:40:57.118576Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.102819ms"} {"level":"info","ts":"2026-02-26T23:40:57.125363Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-02-26T23:40:57.125434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T23:40:57.125463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-02-26T23:40:57.125477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T23:40:57.125494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-02-26T23:40:57.125529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-02-26T23:40:57.130964Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T23:40:57.132423Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T23:40:57.134852Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-26T23:40:57.137016Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T23:40:57.139909Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T23:40:57.14007Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T23:40:57.14017Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T23:40:57.140179Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T23:40:57.14078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-02-26T23:40:57.141078Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-02-26T23:40:57.144634Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-26T23:40:57.144708Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-02-26T23:40:57.144761Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-02-26T23:40:57.144826Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T23:40:57.144851Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T23:40:57.826308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T23:40:57.826358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T23:40:57.826375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-02-26T23:40:57.826387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-02-26T23:40:57.826394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-02-26T23:40:57.826404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-02-26T23:40:57.826412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-02-26T23:40:57.827434Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T23:40:57.82757Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T23:40:57.827711Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T23:40:57.8283Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T23:40:57.828311Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T23:40:57.828344Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T23:40:57.828862Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T23:40:57.828989Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T23:40:57.829028Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T23:40:57.829296Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T23:40:57.829547Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"warn","ts":"2026-02-26T23:41:01.476789Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334365976476231157,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T23:41:01.59082Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.379007176s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T23:41:01.591235Z","caller":"traceutil/trace.go:171","msg":"trace[1839402008] linearizableReadLoop","detail":"{readStateIndex:87; appliedIndex:86; }","duration":"614.846562ms","start":"2026-02-26T23:41:00.976365Z","end":"2026-02-26T23:41:01.591211Z","steps":["trace[1839402008] 'read index received' (duration: 614.580809ms)","trace[1839402008] 'applied index is now lower than readState.Index' (duration: 264.843µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:01.591447Z","caller":"traceutil/trace.go:171","msg":"trace[1644937344] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"1.379784863s","start":"2026-02-26T23:41:00.211592Z","end":"2026-02-26T23:41:01.591377Z","steps":["trace[1644937344] 'process raft request' (duration: 1.37940028s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:01.591557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.130144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T23:41:01.591626Z","caller":"traceutil/trace.go:171","msg":"trace[1959323250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:83; }","duration":"615.302385ms","start":"2026-02-26T23:41:00.9763Z","end":"2026-02-26T23:41:01.591603Z","steps":["trace[1959323250] 'agreement among raft nodes before linearized reading' (duration: 615.028783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:01.591792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:00.976264Z","time spent":"615.458007ms","remote":"127.0.0.1:33152","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-02-26T23:41:01.592135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:00.211576Z","time spent":"1.380003195s","remote":"127.0.0.1:33538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2012,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T23:41:02.847993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142095951s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-26T23:41:02.848104Z","caller":"traceutil/trace.go:171","msg":"trace[131535760] linearizableReadLoop","detail":"{readStateIndex:88; appliedIndex:87; }","duration":"915.704099ms","start":"2026-02-26T23:41:01.932384Z","end":"2026-02-26T23:41:02.848088Z","steps":["trace[131535760] 'read index received' (duration: 53.401µs)","trace[131535760] 'applied index is now lower than readState.Index' (duration: 915.649788ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:02.848207Z","caller":"traceutil/trace.go:171","msg":"trace[1212197729] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"1.250611666s","start":"2026-02-26T23:41:01.597531Z","end":"2026-02-26T23:41:02.848142Z","steps":["trace[1212197729] 'process raft request' (duration: 108.136511ms)","trace[1212197729] 'compare' (duration: 1.14193464s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:02.848228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.84855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-26T23:41:02.848392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:01.597519Z","time spent":"1.250784897s","remote":"127.0.0.1:33538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1917,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T23:41:02.848402Z","caller":"traceutil/trace.go:171","msg":"trace[1214373322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:84; }","duration":"916.070542ms","start":"2026-02-26T23:41:01.93232Z","end":"2026-02-26T23:41:02.848391Z","steps":["trace[1214373322] 'agreement among raft nodes before linearized reading' (duration: 915.8364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:02.84848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:01.932293Z","time spent":"916.174803ms","remote":"127.0.0.1:33454","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-02-26T23:41:03.349065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334365976476231164,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T23:41:03.84963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334365976476231164,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T23:41:04.003441Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.550210922s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-26T23:41:04.076616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000075117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-26T23:41:04.076724Z","caller":"traceutil/trace.go:171","msg":"trace[1412748488] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000230738s","start":"2026-02-26T23:41:02.076461Z","end":"2026-02-26T23:41:04.076691Z","steps":["trace[1412748488] 'agreement among raft nodes before linearized reading' (duration: 2.000113427s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:04.076787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:02.076436Z","time spent":"2.000343069s","remote":"127.0.0.1:33152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/26 23:41:04 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-26T23:41:04.088959Z","caller":"traceutil/trace.go:171","msg":"trace[2022206624] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"1.635814364s","start":"2026-02-26T23:41:02.453096Z","end":"2026-02-26T23:41:04.088911Z","steps":["trace[2022206624] 'process raft request' (duration: 1.550637705s)","trace[2022206624] 'compare' (duration: 83.789136ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:04.089129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:02.453071Z","time spent":"1.635976225s","remote":"127.0.0.1:33374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T23:41:04.089515Z","caller":"traceutil/trace.go:171","msg":"trace[635878586] linearizableReadLoop","detail":"{readStateIndex:91; appliedIndex:88; }","duration":"1.241369314s","start":"2026-02-26T23:41:02.848131Z","end":"2026-02-26T23:41:04.0895Z","steps":["trace[635878586] 'read index received' (duration: 1.155551461s)","trace[635878586] 'applied index is now lower than readState.Index' (duration: 85.816343ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:04.089711Z","caller":"traceutil/trace.go:171","msg":"trace[607479327] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"1.237172068s","start":"2026-02-26T23:41:02.852528Z","end":"2026-02-26T23:41:04.0897Z","steps":["trace[607479327] 'process raft request' (duration: 1.236327051s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:04.08987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:02.970322Z","time spent":"1.119545033s","remote":"127.0.0.1:33244","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T23:41:04.089965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238734172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:heapster\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-26T23:41:04.090071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.579603ms","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-02-26T23:41:04.090126Z","caller":"traceutil/trace.go:171","msg":"trace[1448453059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:86; }","duration":"778.699424ms","start":"2026-02-26T23:41:03.311412Z","end":"2026-02-26T23:41:04.090112Z","steps":["trace[1448453059] 'agreement among raft nodes before linearized reading' (duration: 778.593724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:04.090175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:03.311379Z","time spent":"778.787985ms","remote":"127.0.0.1:33454","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":"info","ts":"2026-02-26T23:41:04.090169Z","caller":"traceutil/trace.go:171","msg":"trace[125298906] range","detail":"{range_begin:/registry/clusterroles/system:heapster; range_end:; response_count:0; response_revision:86; }","duration":"1.238922633s","start":"2026-02-26T23:41:02.851183Z","end":"2026-02-26T23:41:04.090106Z","steps":["trace[125298906] 'agreement among raft nodes before linearized reading' (duration: 1.238690831s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:04.090261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:02.851112Z","time spent":"1.239135785s","remote":"127.0.0.1:33538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:heapster\" "} {"level":"warn","ts":"2026-02-26T23:41:04.090437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:02.852488Z","time spent":"1.237256008s","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T23:41:04.090689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.989688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T23:41:04.090778Z","caller":"traceutil/trace.go:171","msg":"trace[1604907827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:86; }","duration":"762.101339ms","start":"2026-02-26T23:41:03.32865Z","end":"2026-02-26T23:41:04.090751Z","steps":["trace[1604907827] 'agreement among raft nodes before linearized reading' (duration: 761.323672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:04.090821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:03.328639Z","time spent":"762.173339ms","remote":"127.0.0.1:33152","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-02-26T23:41:04.795615Z","caller":"traceutil/trace.go:171","msg":"trace[28703336] linearizableReadLoop","detail":"{readStateIndex:132; appliedIndex:131; }","duration":"107.124348ms","start":"2026-02-26T23:41:04.68847Z","end":"2026-02-26T23:41:04.795595Z","steps":["trace[28703336] 'read index received' (duration: 23.04249ms)","trace[28703336] 'applied index is now lower than readState.Index' (duration: 84.080948ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:04.795767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.274409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T23:41:04.795641Z","caller":"traceutil/trace.go:171","msg":"trace[1091907363] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"121.732264ms","start":"2026-02-26T23:41:04.673883Z","end":"2026-02-26T23:41:04.795616Z","steps":["trace[1091907363] 'process raft request' (duration: 37.625016ms)","trace[1091907363] 'compare' (duration: 84.000157ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:04.795828Z","caller":"traceutil/trace.go:171","msg":"trace[822329885] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:0; response_revision:127; }","duration":"107.37623ms","start":"2026-02-26T23:41:04.688433Z","end":"2026-02-26T23:41:04.795809Z","steps":["trace[822329885] 'agreement among raft nodes before linearized reading' (duration: 107.257629ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:04.97291Z","caller":"traceutil/trace.go:171","msg":"trace[1974341587] linearizableReadLoop","detail":"{readStateIndex:136; appliedIndex:135; }","duration":"100.155607ms","start":"2026-02-26T23:41:04.872727Z","end":"2026-02-26T23:41:04.972882Z","steps":["trace[1974341587] 'read index received' (duration: 27.871622ms)","trace[1974341587] 'applied index is now lower than readState.Index' (duration: 72.282565ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:04.972927Z","caller":"traceutil/trace.go:171","msg":"trace[137241531] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"104.708166ms","start":"2026-02-26T23:41:04.868189Z","end":"2026-02-26T23:41:04.972897Z","steps":["trace[137241531] 'process raft request' (duration: 32.496052ms)","trace[137241531] 'compare' (duration: 72.087343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:04.973236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.399649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:41:04.973414Z","caller":"traceutil/trace.go:171","msg":"trace[1739138385] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:0; response_revision:131; }","duration":"100.720053ms","start":"2026-02-26T23:41:04.872663Z","end":"2026-02-26T23:41:04.973383Z","steps":["trace[1739138385] 'agreement among raft nodes before linearized reading' (duration: 100.325748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:05.202371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.147102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:41:05.202647Z","caller":"traceutil/trace.go:171","msg":"trace[1147151513] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"136.431117ms","start":"2026-02-26T23:41:05.066165Z","end":"2026-02-26T23:41:05.202596Z","steps":["trace[1147151513] 'process raft request' (duration: 17.978233ms)","trace[1147151513] 'compare' (duration: 118.037091ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:05.202682Z","caller":"traceutil/trace.go:171","msg":"trace[343482643] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"129.201677ms","start":"2026-02-26T23:41:05.073443Z","end":"2026-02-26T23:41:05.202644Z","steps":["trace[343482643] 'process raft request' (duration: 129.025355ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:06.04579Z","caller":"traceutil/trace.go:171","msg":"trace[160067304] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"163.293594ms","start":"2026-02-26T23:41:05.882464Z","end":"2026-02-26T23:41:06.045757Z","steps":["trace[160067304] 'process raft request' (duration: 78.778745ms)","trace[160067304] 'compare' (duration: 84.353298ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:06.430175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.027706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:41:06.430308Z","caller":"traceutil/trace.go:171","msg":"trace[2089165556] linearizableReadLoop","detail":"{readStateIndex:184; appliedIndex:183; }","duration":"154.65899ms","start":"2026-02-26T23:41:06.275628Z","end":"2026-02-26T23:41:06.430287Z","steps":["trace[2089165556] 'read index received' (duration: 40.440053ms)","trace[2089165556] 'applied index is now lower than readState.Index' (duration: 114.215657ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:06.430389Z","caller":"traceutil/trace.go:171","msg":"trace[1529862858] transaction","detail":"{read_only:false; response_revision:179; number_of_response:1; }","duration":"177.737876ms","start":"2026-02-26T23:41:06.252639Z","end":"2026-02-26T23:41:06.430377Z","steps":["trace[1529862858] 'process raft request' (duration: 63.413858ms)","trace[1529862858] 'compare' (duration: 113.880643ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:06.430588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.914312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:41:06.4307Z","caller":"traceutil/trace.go:171","msg":"trace[230962133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:179; }","duration":"155.110933ms","start":"2026-02-26T23:41:06.27556Z","end":"2026-02-26T23:41:06.430671Z","steps":["trace[230962133] 'agreement among raft nodes before linearized reading' (duration: 154.849891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:06.430616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.926729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-02-26T23:41:06.430945Z","caller":"traceutil/trace.go:171","msg":"trace[79440068] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:179; }","duration":"144.327172ms","start":"2026-02-26T23:41:06.286575Z","end":"2026-02-26T23:41:06.430902Z","steps":["trace[79440068] 'agreement among raft nodes before linearized reading' (duration: 143.837018ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:07.283999Z","caller":"traceutil/trace.go:171","msg":"trace[657245670] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"134.170484ms","start":"2026-02-26T23:41:07.149781Z","end":"2026-02-26T23:41:07.283951Z","steps":["trace[657245670] 'process raft request' (duration: 63.16712ms)","trace[657245670] 'compare' (duration: 70.735192ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:07.766397Z","caller":"traceutil/trace.go:171","msg":"trace[619238643] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"160.872738ms","start":"2026-02-26T23:41:07.605481Z","end":"2026-02-26T23:41:07.766353Z","steps":["trace[619238643] 'process raft request' (duration: 107.572701ms)","trace[619238643] 'compare' (duration: 53.140856ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:08.599722Z","caller":"traceutil/trace.go:171","msg":"trace[118048030] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"202.095884ms","start":"2026-02-26T23:41:08.397597Z","end":"2026-02-26T23:41:08.599693Z","steps":["trace[118048030] 'process raft request' (duration: 118.354851ms)","trace[118048030] 'compare' (duration: 83.614472ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:08.609654Z","caller":"traceutil/trace.go:171","msg":"trace[407451674] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:259; }","duration":"104.313795ms","start":"2026-02-26T23:41:08.505319Z","end":"2026-02-26T23:41:08.609632Z","steps":["trace[407451674] 'read index received' (duration: 10.730809ms)","trace[407451674] 'applied index is now lower than readState.Index' (duration: 93.581846ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:08.609837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.508486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:41:08.609887Z","caller":"traceutil/trace.go:171","msg":"trace[2099657644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:255; }","duration":"104.570387ms","start":"2026-02-26T23:41:08.505302Z","end":"2026-02-26T23:41:08.609873Z","steps":["trace[2099657644] 'agreement among raft nodes before linearized reading' (duration: 104.455226ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:08.609805Z","caller":"traceutil/trace.go:171","msg":"trace[374055966] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"190.875602ms","start":"2026-02-26T23:41:08.418819Z","end":"2026-02-26T23:41:08.609695Z","steps":["trace[374055966] 'process raft request' (duration: 190.726641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:08.821918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.107205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:41:08.82218Z","caller":"traceutil/trace.go:171","msg":"trace[645389313] linearizableReadLoop","detail":"{readStateIndex:267; appliedIndex:265; }","duration":"167.456227ms","start":"2026-02-26T23:41:08.654703Z","end":"2026-02-26T23:41:08.822159Z","steps":["trace[645389313] 'read index received' (duration: 46.945299ms)","trace[645389313] 'applied index is now lower than readState.Index' (duration: 120.509648ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:08.822166Z","caller":"traceutil/trace.go:171","msg":"trace[52813797] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"175.04195ms","start":"2026-02-26T23:41:08.647088Z","end":"2026-02-26T23:41:08.82213Z","steps":["trace[52813797] 'process raft request' (duration: 54.620312ms)","trace[52813797] 'compare' (duration: 119.863883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:08.822312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.602369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-26T23:41:08.82235Z","caller":"traceutil/trace.go:171","msg":"trace[406856632] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:261; }","duration":"167.666529ms","start":"2026-02-26T23:41:08.654671Z","end":"2026-02-26T23:41:08.822338Z","steps":["trace[406856632] 'agreement among raft nodes before linearized reading' (duration: 167.560808ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:08.822468Z","caller":"traceutil/trace.go:171","msg":"trace[647349807] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"174.651628ms","start":"2026-02-26T23:41:08.647792Z","end":"2026-02-26T23:41:08.822444Z","steps":["trace[647349807] 'process raft request' (duration: 174.258704ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:09.399739Z","caller":"traceutil/trace.go:171","msg":"trace[1730064164] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"114.829022ms","start":"2026-02-26T23:41:09.284863Z","end":"2026-02-26T23:41:09.399692Z","steps":["trace[1730064164] 'process raft request' (duration: 94.933229ms)","trace[1730064164] 'compare' (duration: 19.643841ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:09.803096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.089659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:41:09.803268Z","caller":"traceutil/trace.go:171","msg":"trace[358094659] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"155.676606ms","start":"2026-02-26T23:41:09.647576Z","end":"2026-02-26T23:41:09.803252Z","steps":["trace[358094659] 'process raft request' (duration: 155.630106ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:09.80329Z","caller":"traceutil/trace.go:171","msg":"trace[1794189169] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"175.992244ms","start":"2026-02-26T23:41:09.627268Z","end":"2026-02-26T23:41:09.803261Z","steps":["trace[1794189169] 'process raft request' (duration: 51.661884ms)","trace[1794189169] 'compare' (duration: 123.912056ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:10.379551Z","caller":"traceutil/trace.go:171","msg":"trace[942779037] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:295; }","duration":"130.871461ms","start":"2026-02-26T23:41:10.248656Z","end":"2026-02-26T23:41:10.379527Z","steps":["trace[942779037] 'read index received' (duration: 130.66195ms)","trace[942779037] 'applied index is now lower than readState.Index' (duration: 208.641µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:10.379679Z","caller":"traceutil/trace.go:171","msg":"trace[1198857690] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"151.012605ms","start":"2026-02-26T23:41:10.228657Z","end":"2026-02-26T23:41:10.37967Z","steps":["trace[1198857690] 'process raft request' (duration: 150.750963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:10.379983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.109365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T23:41:10.380055Z","caller":"traceutil/trace.go:171","msg":"trace[991024774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:289; }","duration":"131.224705ms","start":"2026-02-26T23:41:10.248812Z","end":"2026-02-26T23:41:10.380037Z","steps":["trace[991024774] 'agreement among raft nodes before linearized reading' (duration: 130.985263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:10.380457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.78338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T23:41:10.380512Z","caller":"traceutil/trace.go:171","msg":"trace[1749608715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:289; }","duration":"131.85207ms","start":"2026-02-26T23:41:10.248647Z","end":"2026-02-26T23:41:10.380499Z","steps":["trace[1749608715] 'agreement among raft nodes before linearized reading' (duration: 131.285126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:11.295996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.604062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:41:11.296133Z","caller":"traceutil/trace.go:171","msg":"trace[3659087] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"155.746271ms","start":"2026-02-26T23:41:11.140362Z","end":"2026-02-26T23:41:11.296108Z","steps":["trace[3659087] 'process raft request' (duration: 36.560553ms)","trace[3659087] 'compare' (duration: 118.443431ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:11.313557Z","caller":"traceutil/trace.go:171","msg":"trace[1299260796] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:334; }","duration":"136.560627ms","start":"2026-02-26T23:41:11.176969Z","end":"2026-02-26T23:41:11.313529Z","steps":["trace[1299260796] 'read index received' (duration: 136.423916ms)","trace[1299260796] 'applied index is now lower than readState.Index' (duration: 132.351µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:11.313725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.738448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:41:11.313765Z","caller":"traceutil/trace.go:171","msg":"trace[98409064] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:322; }","duration":"136.835219ms","start":"2026-02-26T23:41:11.176919Z","end":"2026-02-26T23:41:11.313754Z","steps":["trace[98409064] 'agreement among raft nodes before linearized reading' (duration: 136.726979ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:11.313905Z","caller":"traceutil/trace.go:171","msg":"trace[186990895] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"163.402962ms","start":"2026-02-26T23:41:11.150447Z","end":"2026-02-26T23:41:11.31385Z","steps":["trace[186990895] 'process raft request' (duration: 162.960569ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:11.536238Z","caller":"traceutil/trace.go:171","msg":"trace[785942551] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:337; }","duration":"158.378342ms","start":"2026-02-26T23:41:11.377828Z","end":"2026-02-26T23:41:11.536206Z","steps":["trace[785942551] 'read index received' (duration: 67.476962ms)","trace[785942551] 'applied index is now lower than readState.Index' (duration: 90.90009ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:11.536456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.603914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-26T23:41:11.536504Z","caller":"traceutil/trace.go:171","msg":"trace[109606496] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:325; }","duration":"158.719674ms","start":"2026-02-26T23:41:11.377768Z","end":"2026-02-26T23:41:11.536487Z","steps":["trace[109606496] 'agreement among raft nodes before linearized reading' (duration: 158.573303ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:11.536384Z","caller":"traceutil/trace.go:171","msg":"trace[1347764336] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"181.534558ms","start":"2026-02-26T23:41:11.354797Z","end":"2026-02-26T23:41:11.536332Z","steps":["trace[1347764336] 'process raft request' (duration: 90.610888ms)","trace[1347764336] 'compare' (duration: 90.633208ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:11.691262Z","caller":"traceutil/trace.go:171","msg":"trace[1566492020] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"106.596826ms","start":"2026-02-26T23:41:11.584629Z","end":"2026-02-26T23:41:11.691226Z","steps":["trace[1566492020] 'process raft request' (duration: 84.413278ms)","trace[1566492020] 'compare' (duration: 21.894096ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:12.124034Z","caller":"traceutil/trace.go:171","msg":"trace[1659163693] transaction","detail":"{read_only:false; number_of_response:0; response_revision:332; }","duration":"125.256767ms","start":"2026-02-26T23:41:11.998692Z","end":"2026-02-26T23:41:12.123949Z","steps":["trace[1659163693] 'process raft request' (duration: 125.136996ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:12.192205Z","caller":"traceutil/trace.go:171","msg":"trace[1489077756] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:346; }","duration":"165.185333ms","start":"2026-02-26T23:41:12.027002Z","end":"2026-02-26T23:41:12.192187Z","steps":["trace[1489077756] 'read index received' (duration: 96.94899ms)","trace[1489077756] 'applied index is now lower than readState.Index' (duration: 68.235203ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:12.192225Z","caller":"traceutil/trace.go:171","msg":"trace[578831779] transaction","detail":"{read_only:false; number_of_response:0; response_revision:332; }","duration":"192.36262ms","start":"2026-02-26T23:41:11.999809Z","end":"2026-02-26T23:41:12.192172Z","steps":["trace[578831779] 'process raft request' (duration: 192.140618ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:12.192348Z","caller":"traceutil/trace.go:171","msg":"trace[1580444409] transaction","detail":"{read_only:false; number_of_response:0; response_revision:332; }","duration":"192.422921ms","start":"2026-02-26T23:41:11.999915Z","end":"2026-02-26T23:41:12.192338Z","steps":["trace[1580444409] 'process raft request' (duration: 192.128478ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:12.192611Z","caller":"traceutil/trace.go:171","msg":"trace[76310745] transaction","detail":"{read_only:false; number_of_response:0; response_revision:332; }","duration":"190.574617ms","start":"2026-02-26T23:41:12.001983Z","end":"2026-02-26T23:41:12.192558Z","steps":["trace[76310745] 'process raft request' (duration: 190.095772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:12.192999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.518179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-26T23:41:12.193051Z","caller":"traceutil/trace.go:171","msg":"trace[545646] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:333; }","duration":"115.632371ms","start":"2026-02-26T23:41:12.077404Z","end":"2026-02-26T23:41:12.193036Z","steps":["trace[545646] 'agreement among raft nodes before linearized reading' (duration: 115.46473ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:12.193189Z","caller":"traceutil/trace.go:171","msg":"trace[427642726] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"188.613131ms","start":"2026-02-26T23:41:12.004529Z","end":"2026-02-26T23:41:12.193142Z","steps":["trace[427642726] 'process raft request' (duration: 187.574162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:12.193537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.511904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-26T23:41:12.193624Z","caller":"traceutil/trace.go:171","msg":"trace[1063662386] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:333; }","duration":"166.633036ms","start":"2026-02-26T23:41:12.026977Z","end":"2026-02-26T23:41:12.19361Z","steps":["trace[1063662386] 'agreement among raft nodes before linearized reading' (duration: 165.667388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:12.454017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.011172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T23:41:12.454155Z","caller":"traceutil/trace.go:171","msg":"trace[793294123] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:350; }","duration":"247.974963ms","start":"2026-02-26T23:41:12.206156Z","end":"2026-02-26T23:41:12.454131Z","steps":["trace[793294123] 'read index received' (duration: 191.712µs)","trace[793294123] 'applied index is now lower than readState.Index' (duration: 247.781911ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:12.454248Z","caller":"traceutil/trace.go:171","msg":"trace[722155903] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"248.123314ms","start":"2026-02-26T23:41:12.206115Z","end":"2026-02-26T23:41:12.454238Z","steps":["trace[722155903] 'process raft request' (duration: 116.784689ms)","trace[722155903] 'compare' (duration: 130.850121ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:12.454414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.274225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-26T23:41:12.454437Z","caller":"traceutil/trace.go:171","msg":"trace[844358150] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:334; }","duration":"248.309645ms","start":"2026-02-26T23:41:12.206119Z","end":"2026-02-26T23:41:12.454428Z","steps":["trace[844358150] 'agreement among raft nodes before linearized reading' (duration: 248.257755ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:13.835613Z","caller":"traceutil/trace.go:171","msg":"trace[1063242470] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"166.157768ms","start":"2026-02-26T23:41:13.669416Z","end":"2026-02-26T23:41:13.835574Z","steps":["trace[1063242470] 'process raft request' (duration: 142.730674ms)","trace[1063242470] 'compare' (duration: 23.096892ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:13.83566Z","caller":"traceutil/trace.go:171","msg":"trace[877034283] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"157.249439ms","start":"2026-02-26T23:41:13.678379Z","end":"2026-02-26T23:41:13.835629Z","steps":["trace[877034283] 'process raft request' (duration: 157.170048ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:13.835671Z","caller":"traceutil/trace.go:171","msg":"trace[1049637616] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:374; }","duration":"158.829982ms","start":"2026-02-26T23:41:13.676816Z","end":"2026-02-26T23:41:13.835646Z","steps":["trace[1049637616] 'read index received' (duration: 135.293757ms)","trace[1049637616] 'applied index is now lower than readState.Index' (duration: 23.534535ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:13.836115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.454032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-26T23:41:13.836166Z","caller":"traceutil/trace.go:171","msg":"trace[1219221648] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:359; }","duration":"109.566203ms","start":"2026-02-26T23:41:13.726585Z","end":"2026-02-26T23:41:13.836151Z","steps":["trace[1219221648] 'agreement among raft nodes before linearized reading' (duration: 109.444532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:13.836829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.989432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-26T23:41:13.836885Z","caller":"traceutil/trace.go:171","msg":"trace[1049222825] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:359; }","duration":"160.103162ms","start":"2026-02-26T23:41:13.676768Z","end":"2026-02-26T23:41:13.836871Z","steps":["trace[1049222825] 'agreement among raft nodes before linearized reading' (duration: 159.012973ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:14.142861Z","caller":"traceutil/trace.go:171","msg":"trace[185740877] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"133.038569ms","start":"2026-02-26T23:41:14.009786Z","end":"2026-02-26T23:41:14.142825Z","steps":["trace[185740877] 'process raft request' (duration: 125.663161ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:14.143225Z","caller":"traceutil/trace.go:171","msg":"trace[1494156460] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:390; }","duration":"133.072359ms","start":"2026-02-26T23:41:14.009994Z","end":"2026-02-26T23:41:14.143066Z","steps":["trace[1494156460] 'read index received' (duration: 37.455883ms)","trace[1494156460] 'applied index is now lower than readState.Index' (duration: 95.615446ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:14.143331Z","caller":"traceutil/trace.go:171","msg":"trace[595577077] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"129.657482ms","start":"2026-02-26T23:41:14.013509Z","end":"2026-02-26T23:41:14.143166Z","steps":["trace[595577077] 'process raft request' (duration: 129.361559ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:14.143308Z","caller":"traceutil/trace.go:171","msg":"trace[969191023] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"131.708237ms","start":"2026-02-26T23:41:14.011551Z","end":"2026-02-26T23:41:14.14326Z","steps":["trace[969191023] 'process raft request' (duration: 131.173533ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:14.143505Z","caller":"traceutil/trace.go:171","msg":"trace[656018713] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"129.561331ms","start":"2026-02-26T23:41:14.013924Z","end":"2026-02-26T23:41:14.143485Z","steps":["trace[656018713] 'process raft request' (duration: 129.013367ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:14.143509Z","caller":"traceutil/trace.go:171","msg":"trace[1712152466] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"129.252209ms","start":"2026-02-26T23:41:14.014222Z","end":"2026-02-26T23:41:14.143474Z","steps":["trace[1712152466] 'process raft request' (duration: 128.790845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:14.143594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.573083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-26T23:41:14.143707Z","caller":"traceutil/trace.go:171","msg":"trace[1299446815] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:377; }","duration":"133.717733ms","start":"2026-02-26T23:41:14.009968Z","end":"2026-02-26T23:41:14.143686Z","steps":["trace[1299446815] 'agreement among raft nodes before linearized reading' (duration: 133.525872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:37.113789Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334365976476232057,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T23:41:37.148612Z","caller":"traceutil/trace.go:171","msg":"trace[258856138] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"535.220396ms","start":"2026-02-26T23:41:36.613363Z","end":"2026-02-26T23:41:37.148584Z","steps":["trace[258856138] 'read index received' (duration: 535.032044ms)","trace[258856138] 'applied index is now lower than readState.Index' (duration: 187.231µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:37.148739Z","caller":"traceutil/trace.go:171","msg":"trace[1138345904] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"538.349996ms","start":"2026-02-26T23:41:36.610342Z","end":"2026-02-26T23:41:37.148692Z","steps":["trace[1138345904] 'process raft request' (duration: 538.019954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:37.148838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.703331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T23:41:37.148887Z","caller":"traceutil/trace.go:171","msg":"trace[2066643608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"173.793471ms","start":"2026-02-26T23:41:36.97508Z","end":"2026-02-26T23:41:37.148873Z","steps":["trace[2066643608] 'agreement among raft nodes before linearized reading' (duration: 173.696661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:37.148889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.492257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T23:41:37.148923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:36.610303Z","time spent":"538.508657ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T23:41:37.148982Z","caller":"traceutil/trace.go:171","msg":"trace[1526053213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"535.645968ms","start":"2026-02-26T23:41:36.61331Z","end":"2026-02-26T23:41:37.148956Z","steps":["trace[1526053213] 'agreement among raft nodes before linearized reading' (duration: 535.414666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:37.149027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:36.613287Z","time spent":"535.731549ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T23:41:37.148996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.834368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T23:41:37.149111Z","caller":"traceutil/trace.go:171","msg":"trace[1755763320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"196.011679ms","start":"2026-02-26T23:41:36.953066Z","end":"2026-02-26T23:41:37.149078Z","steps":["trace[1755763320] 'agreement among raft nodes before linearized reading' (duration: 195.692867ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:45.452689Z","caller":"traceutil/trace.go:171","msg":"trace[406832094] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"259.420943ms","start":"2026-02-26T23:41:45.193234Z","end":"2026-02-26T23:41:45.452655Z","steps":["trace[406832094] 'read index received' (duration: 259.16562ms)","trace[406832094] 'applied index is now lower than readState.Index' (duration: 253.993µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:45.452789Z","caller":"traceutil/trace.go:171","msg":"trace[1926662909] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"338.727427ms","start":"2026-02-26T23:41:45.114014Z","end":"2026-02-26T23:41:45.452741Z","steps":["trace[1926662909] 'process raft request' (duration: 338.436715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:45.4533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.578509ms","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-02-26T23:41:45.453355Z","caller":"traceutil/trace.go:171","msg":"trace[1184477372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:567; }","duration":"227.6735ms","start":"2026-02-26T23:41:45.225668Z","end":"2026-02-26T23:41:45.453342Z","steps":["trace[1184477372] 'agreement among raft nodes before linearized reading' (duration: 227.573359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:45.453072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.809265ms","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-02-26T23:41:45.453381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.366003ms","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-02-26T23:41:45.453499Z","caller":"traceutil/trace.go:171","msg":"trace[1367338984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"223.516054ms","start":"2026-02-26T23:41:45.229958Z","end":"2026-02-26T23:41:45.453474Z","steps":["trace[1367338984] 'agreement among raft nodes before linearized reading' (duration: 223.261292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:45.45322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.914064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T23:41:45.453636Z","caller":"traceutil/trace.go:171","msg":"trace[1459311383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"241.307426ms","start":"2026-02-26T23:41:45.212278Z","end":"2026-02-26T23:41:45.453586Z","steps":["trace[1459311383] 'agreement among raft nodes before linearized reading' (duration: 240.842904ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:45.453695Z","caller":"traceutil/trace.go:171","msg":"trace[106727938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"260.247027ms","start":"2026-02-26T23:41:45.193175Z","end":"2026-02-26T23:41:45.453422Z","steps":["trace[106727938] 'agreement among raft nodes before linearized reading' (duration: 259.699034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:45.453898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:45.11399Z","time spent":"339.774713ms","remote":"127.0.0.1:33374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T23:41:45.95949Z","caller":"traceutil/trace.go:171","msg":"trace[1323807443] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"499.551811ms","start":"2026-02-26T23:41:45.459902Z","end":"2026-02-26T23:41:45.959454Z","steps":["trace[1323807443] 'process raft request' (duration: 499.484411ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:45.959551Z","caller":"traceutil/trace.go:171","msg":"trace[168770854] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"499.724853ms","start":"2026-02-26T23:41:45.459784Z","end":"2026-02-26T23:41:45.959508Z","steps":["trace[168770854] 'process raft request' (duration: 499.43883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:45.959685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:45.459887Z","time spent":"499.697533ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T23:41:45.959737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:45.459755Z","time spent":"499.880173ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T23:41:45.960141Z","caller":"traceutil/trace.go:171","msg":"trace[1501403015] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"500.120655ms","start":"2026-02-26T23:41:45.460007Z","end":"2026-02-26T23:41:45.960127Z","steps":["trace[1501403015] 'process raft request' (duration: 499.41321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:45.960222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:45.459997Z","time spent":"500.186106ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T23:41:45.96011Z","caller":"traceutil/trace.go:171","msg":"trace[406396600] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:593; }","duration":"367.113138ms","start":"2026-02-26T23:41:45.592949Z","end":"2026-02-26T23:41:45.960062Z","steps":["trace[406396600] 'read index received' (duration: 366.182661ms)","trace[406396600] 'applied index is now lower than readState.Index' (duration: 926.937µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T23:41:45.960451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.47578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:41:45.960512Z","caller":"traceutil/trace.go:171","msg":"trace[322406031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"367.62038ms","start":"2026-02-26T23:41:45.592878Z","end":"2026-02-26T23:41:45.960498Z","steps":["trace[322406031] 'agreement among raft nodes before linearized reading' (duration: 367.300139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:45.960664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:45.592853Z","time spent":"367.757271ms","remote":"127.0.0.1:33150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T23:41:46.802482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.574158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T23:41:46.802606Z","caller":"traceutil/trace.go:171","msg":"trace[1475206463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"209.762519ms","start":"2026-02-26T23:41:46.592812Z","end":"2026-02-26T23:41:46.802575Z","steps":["trace[1475206463] 'range keys from in-memory index tree' (duration: 209.469308ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:48.114814Z","caller":"traceutil/trace.go:171","msg":"trace[1012185027] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"151.327173ms","start":"2026-02-26T23:41:47.963454Z","end":"2026-02-26T23:41:48.114781Z","steps":["trace[1012185027] 'read index received' (duration: 151.107561ms)","trace[1012185027] 'applied index is now lower than readState.Index' (duration: 218.422µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:48.114936Z","caller":"traceutil/trace.go:171","msg":"trace[1327044977] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"631.476204ms","start":"2026-02-26T23:41:47.483432Z","end":"2026-02-26T23:41:48.114908Z","steps":["trace[1327044977] 'process raft request' (duration: 631.128692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:48.115093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.8575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T23:41:48.115128Z","caller":"traceutil/trace.go:171","msg":"trace[2059147305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"150.922801ms","start":"2026-02-26T23:41:47.964194Z","end":"2026-02-26T23:41:48.115117Z","steps":["trace[2059147305] 'agreement among raft nodes before linearized reading' (duration: 150.76027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:48.115142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.74463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-26T23:41:48.115167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.677455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-26T23:41:48.115175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T23:41:47.483406Z","time spent":"631.618597ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T23:41:48.115264Z","caller":"traceutil/trace.go:171","msg":"trace[481613747] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"151.824075ms","start":"2026-02-26T23:41:47.963414Z","end":"2026-02-26T23:41:48.115238Z","steps":["trace[481613747] 'agreement among raft nodes before linearized reading' (duration: 151.538534ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:48.115201Z","caller":"traceutil/trace.go:171","msg":"trace[999719262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"150.86084ms","start":"2026-02-26T23:41:47.964324Z","end":"2026-02-26T23:41:48.115185Z","steps":["trace[999719262] 'agreement among raft nodes before linearized reading' (duration: 150.702759ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:48.32336Z","caller":"traceutil/trace.go:171","msg":"trace[1792723886] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"202.988426ms","start":"2026-02-26T23:41:48.120332Z","end":"2026-02-26T23:41:48.32332Z","steps":["trace[1792723886] 'process raft request' (duration: 137.735796ms)","trace[1792723886] 'compare' (duration: 64.949648ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:48.326581Z","caller":"traceutil/trace.go:171","msg":"trace[1819667585] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:597; }","duration":"148.252791ms","start":"2026-02-26T23:41:48.178305Z","end":"2026-02-26T23:41:48.326558Z","steps":["trace[1819667585] 'read index received' (duration: 79.847901ms)","trace[1819667585] 'applied index is now lower than readState.Index' (duration: 68.40408ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T23:41:48.326675Z","caller":"traceutil/trace.go:171","msg":"trace[1327350859] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"205.920076ms","start":"2026-02-26T23:41:48.120725Z","end":"2026-02-26T23:41:48.326645Z","steps":["trace[1327350859] 'process raft request' (duration: 205.802844ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T23:41:48.326692Z","caller":"traceutil/trace.go:171","msg":"trace[1976872803] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"206.044676ms","start":"2026-02-26T23:41:48.120581Z","end":"2026-02-26T23:41:48.326626Z","steps":["trace[1976872803] 'process raft request' (duration: 205.817334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T23:41:48.326796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.477163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T23:41:48.326827Z","caller":"traceutil/trace.go:171","msg":"trace[59638861] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:575; }","duration":"148.557835ms","start":"2026-02-26T23:41:48.178262Z","end":"2026-02-26T23:41:48.32682Z","steps":["trace[59638861] 'agreement among raft nodes before linearized reading' (duration: 148.395654ms)"],"step_count":1}