{"level":"warn","ts":"2026-05-29T05:05:45.969611Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T05:05:45.969711Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-29T05:05:45.969791Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T05:05:45.969807Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-29T05:05:45.969828Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T05:05:45.970668Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-05-29T05:05:45.970782Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-29T05:05:45.97649Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.431067ms"} {"level":"info","ts":"2026-05-29T05:05:45.990235Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-05-29T05:05:45.990467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T05:05:45.990666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-05-29T05:05:45.990736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T05:05:45.990783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-05-29T05:05:45.990866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-05-29T05:05:45.998241Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T05:05:45.999453Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T05:05:46.000576Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-29T05:05:46.0047Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T05:05:46.004894Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T05:05:46.007703Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T05:05:46.007849Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T05:05:46.007975Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T05:05:46.008352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-05-29T05:05:46.011681Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-29T05:05:46.01362Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T05:05:46.013751Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-29T05:05:46.013799Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-29T05:05:46.013862Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T05:05:46.013909Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T05:05:46.695504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T05:05:46.695548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T05:05:46.695598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-05-29T05:05:46.695614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-05-29T05:05:46.695623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-29T05:05:46.695632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-05-29T05:05:46.695642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-29T05:05:46.702653Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T05:05:46.702748Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:05:46.70286Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:05:46.702911Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:05:46.703333Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T05:05:46.703417Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T05:05:46.703519Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:05:46.703604Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:05:46.703648Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:05:46.705534Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T05:05:46.706454Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-05-29T05:06:06.58931Z","caller":"traceutil/trace.go:171","msg":"trace[1642538367] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"112.168993ms","start":"2026-05-29T05:06:06.477109Z","end":"2026-05-29T05:06:06.589278Z","steps":["trace[1642538367] 'process raft request' (duration: 111.972629ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:23.11066Z","caller":"traceutil/trace.go:171","msg":"trace[1489911158] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"678.197653ms","start":"2026-05-29T05:06:22.432428Z","end":"2026-05-29T05:06:23.110625Z","steps":["trace[1489911158] 'process raft request' (duration: 678.033129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:23.111317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.432403Z","time spent":"678.399498ms","remote":"127.0.0.1:35664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:06:23.118381Z","caller":"traceutil/trace.go:171","msg":"trace[1959616924] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"680.953188ms","start":"2026-05-29T05:06:22.437392Z","end":"2026-05-29T05:06:23.118345Z","steps":["trace[1959616924] 'process raft request' (duration: 680.59254ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:23.118439Z","caller":"traceutil/trace.go:171","msg":"trace[350662225] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"680.959768ms","start":"2026-05-29T05:06:22.437445Z","end":"2026-05-29T05:06:23.118404Z","steps":["trace[350662225] 'process raft request' (duration: 680.716883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:23.118532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.437376Z","time spent":"681.084631ms","remote":"127.0.0.1:35664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:06:23.118566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.437439Z","time spent":"681.063011ms","remote":"127.0.0.1:35664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:06:23.11855Z","caller":"traceutil/trace.go:171","msg":"trace[957830872] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"675.406657ms","start":"2026-05-29T05:06:22.443085Z","end":"2026-05-29T05:06:23.118491Z","steps":["trace[957830872] 'process raft request' (duration: 675.168142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:23.1187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.443067Z","time spent":"675.559922ms","remote":"127.0.0.1:35664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:06:23.118883Z","caller":"traceutil/trace.go:171","msg":"trace[203685817] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"670.640845ms","start":"2026-05-29T05:06:22.448228Z","end":"2026-05-29T05:06:23.118869Z","steps":["trace[203685817] 'process raft request' (duration: 670.150964ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:23.118907Z","caller":"traceutil/trace.go:171","msg":"trace[1548236810] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"670.719048ms","start":"2026-05-29T05:06:22.448159Z","end":"2026-05-29T05:06:23.118878Z","steps":["trace[1548236810] 'process raft request' (duration: 670.168335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:23.118963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.448144Z","time spent":"670.788269ms","remote":"127.0.0.1:35664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:06:23.118957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.448221Z","time spent":"670.702408ms","remote":"127.0.0.1:35664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:06:23.119037Z","caller":"traceutil/trace.go:171","msg":"trace[1061529037] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"670.694667ms","start":"2026-05-29T05:06:22.44833Z","end":"2026-05-29T05:06:23.119025Z","steps":["trace[1061529037] 'process raft request' (duration: 670.103923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:23.119107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.448323Z","time spent":"670.752878ms","remote":"127.0.0.1:35664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:06:23.119121Z","caller":"traceutil/trace.go:171","msg":"trace[1200671277] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"670.532803ms","start":"2026-05-29T05:06:22.44858Z","end":"2026-05-29T05:06:23.119113Z","steps":["trace[1200671277] 'process raft request' (duration: 670.069432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:23.119159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.448572Z","time spent":"670.570144ms","remote":"127.0.0.1:35664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:06:23.119319Z","caller":"traceutil/trace.go:171","msg":"trace[716960296] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"665.619548ms","start":"2026-05-29T05:06:22.453688Z","end":"2026-05-29T05:06:23.119307Z","steps":["trace[716960296] 'process raft request' (duration: 665.222038ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:23.119355Z","caller":"traceutil/trace.go:171","msg":"trace[944586144] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"660.235572ms","start":"2026-05-29T05:06:22.459111Z","end":"2026-05-29T05:06:23.119347Z","steps":["trace[944586144] 'process raft request' (duration: 660.002646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:23.119372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.453679Z","time spent":"665.669309ms","remote":"127.0.0.1:35664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:06:23.119393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.459095Z","time spent":"660.277772ms","remote":"127.0.0.1:35664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:06:23.119506Z","caller":"traceutil/trace.go:171","msg":"trace[118123202] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"647.794789ms","start":"2026-05-29T05:06:22.471704Z","end":"2026-05-29T05:06:23.119499Z","steps":["trace[118123202] 'process raft request' (duration: 647.582904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:23.119546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.471692Z","time spent":"647.8369ms","remote":"127.0.0.1:35664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:06:23.119701Z","caller":"traceutil/trace.go:171","msg":"trace[1584929786] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"513.353663ms","start":"2026-05-29T05:06:22.606339Z","end":"2026-05-29T05:06:23.119692Z","steps":["trace[1584929786] 'process raft request' (duration: 513.171718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:23.119768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.6063Z","time spent":"513.450745ms","remote":"127.0.0.1:35664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:06:23.119793Z","caller":"traceutil/trace.go:171","msg":"trace[181317400] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:501; }","duration":"361.014626ms","start":"2026-05-29T05:06:22.758768Z","end":"2026-05-29T05:06:23.119782Z","steps":["trace[181317400] 'read index received' (duration: 351.786339ms)","trace[181317400] 'applied index is now lower than readState.Index' (duration: 9.227147ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:06:23.119959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.178429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:06:23.120006Z","caller":"traceutil/trace.go:171","msg":"trace[1538012338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"361.309433ms","start":"2026-05-29T05:06:22.758688Z","end":"2026-05-29T05:06:23.119998Z","steps":["trace[1538012338] 'agreement among raft nodes before linearized reading' (duration: 361.147509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:23.120026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.758638Z","time spent":"361.384264ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:06:23.133993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.374559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:06:23.134046Z","caller":"traceutil/trace.go:171","msg":"trace[678836302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:494; }","duration":"374.456301ms","start":"2026-05-29T05:06:22.759577Z","end":"2026-05-29T05:06:23.134033Z","steps":["trace[678836302] 'agreement among raft nodes before linearized reading' (duration: 374.353809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:23.134071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:22.759566Z","time spent":"374.499952ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:06:23.134599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.820907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:06:23.134623Z","caller":"traceutil/trace.go:171","msg":"trace[2019114293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"149.867618ms","start":"2026-05-29T05:06:22.984748Z","end":"2026-05-29T05:06:23.134616Z","steps":["trace[2019114293] 'agreement among raft nodes before linearized reading' (duration: 149.771546ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:27.888343Z","caller":"traceutil/trace.go:171","msg":"trace[239114906] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"106.744526ms","start":"2026-05-29T05:06:27.78157Z","end":"2026-05-29T05:06:27.888314Z","steps":["trace[239114906] 'process raft request' (duration: 106.198863ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:29.307317Z","caller":"traceutil/trace.go:171","msg":"trace[1181830491] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"140.138281ms","start":"2026-05-29T05:06:29.16715Z","end":"2026-05-29T05:06:29.307289Z","steps":["trace[1181830491] 'read index received' (duration: 139.883505ms)","trace[1181830491] 'applied index is now lower than readState.Index' (duration: 253.806µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:06:29.307543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.379916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T05:06:29.307588Z","caller":"traceutil/trace.go:171","msg":"trace[1412341191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"140.440657ms","start":"2026-05-29T05:06:29.167134Z","end":"2026-05-29T05:06:29.307575Z","steps":["trace[1412341191] 'agreement among raft nodes before linearized reading' (duration: 140.281034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:29.307596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.868228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T05:06:29.307545Z","caller":"traceutil/trace.go:171","msg":"trace[1041910749] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"224.745678ms","start":"2026-05-29T05:06:29.082677Z","end":"2026-05-29T05:06:29.307422Z","steps":["trace[1041910749] 'process raft request' (duration: 224.461361ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:29.307646Z","caller":"traceutil/trace.go:171","msg":"trace[2063287634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"137.95849ms","start":"2026-05-29T05:06:29.169674Z","end":"2026-05-29T05:06:29.307632Z","steps":["trace[2063287634] 'agreement among raft nodes before linearized reading' (duration: 137.816796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:29.307827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.60115ms","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-05-29T05:06:29.307953Z","caller":"traceutil/trace.go:171","msg":"trace[765995169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"134.766104ms","start":"2026-05-29T05:06:29.173154Z","end":"2026-05-29T05:06:29.30792Z","steps":["trace[765995169] 'agreement among raft nodes before linearized reading' (duration: 134.404026ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:29.511766Z","caller":"traceutil/trace.go:171","msg":"trace[762308872] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"178.678149ms","start":"2026-05-29T05:06:29.333049Z","end":"2026-05-29T05:06:29.511727Z","steps":["trace[762308872] 'process raft request' (duration: 177.615534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:30.333565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.626613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-29T05:06:30.333693Z","caller":"traceutil/trace.go:171","msg":"trace[308689042] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:592; }","duration":"621.814818ms","start":"2026-05-29T05:06:29.711854Z","end":"2026-05-29T05:06:30.333669Z","steps":["trace[308689042] 'range keys from in-memory index tree' (duration: 621.51885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:30.333741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:29.711834Z","time spent":"621.896689ms","remote":"127.0.0.1:35652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"info","ts":"2026-05-29T05:06:30.333881Z","caller":"traceutil/trace.go:171","msg":"trace[1952183575] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"130.248283ms","start":"2026-05-29T05:06:30.20361Z","end":"2026-05-29T05:06:30.333858Z","steps":["trace[1952183575] 'read index received' (duration: 115.30996ms)","trace[1952183575] 'applied index is now lower than readState.Index' (duration: 14.937293ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:06:30.33402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.394786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:06:30.334046Z","caller":"traceutil/trace.go:171","msg":"trace[1929918699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"130.498118ms","start":"2026-05-29T05:06:30.203537Z","end":"2026-05-29T05:06:30.334036Z","steps":["trace[1929918699] 'agreement among raft nodes before linearized reading' (duration: 130.404296ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:30.334369Z","caller":"traceutil/trace.go:171","msg":"trace[122826712] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"310.651997ms","start":"2026-05-29T05:06:30.023663Z","end":"2026-05-29T05:06:30.334315Z","steps":["trace[122826712] 'process raft request' (duration: 295.712814ms)","trace[122826712] 'compare' (duration: 14.088493ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:06:30.335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:30.023611Z","time spent":"310.958245ms","remote":"127.0.0.1:35710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:06:31.539338Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.147892689s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:06:31.545466Z","caller":"traceutil/trace.go:171","msg":"trace[1461529792] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"215.423882ms","start":"2026-05-29T05:06:31.330002Z","end":"2026-05-29T05:06:31.545426Z","steps":["trace[1461529792] 'read index received' (duration: 209.657846ms)","trace[1461529792] 'applied index is now lower than readState.Index' (duration: 5.764505ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:06:31.545699Z","caller":"traceutil/trace.go:171","msg":"trace[1927429756] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.154339511s","start":"2026-05-29T05:06:30.391344Z","end":"2026-05-29T05:06:31.545684Z","steps":["trace[1927429756] 'process raft request' (duration: 1.148454702s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:31.545819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:06:30.391325Z","time spent":"1.154415022s","remote":"127.0.0.1:35842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:06:31.546314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.299363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T05:06:31.546335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.899783ms","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-05-29T05:06:31.546387Z","caller":"traceutil/trace.go:171","msg":"trace[302207591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"191.027396ms","start":"2026-05-29T05:06:31.355346Z","end":"2026-05-29T05:06:31.546374Z","steps":["trace[302207591] 'agreement among raft nodes before linearized reading' (duration: 190.812811ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:31.546393Z","caller":"traceutil/trace.go:171","msg":"trace[42781902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"216.426806ms","start":"2026-05-29T05:06:31.329943Z","end":"2026-05-29T05:06:31.54637Z","steps":["trace[42781902] 'agreement among raft nodes before linearized reading' (duration: 216.17209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:31.546462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.533931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T05:06:31.546585Z","caller":"traceutil/trace.go:171","msg":"trace[832645146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"189.776326ms","start":"2026-05-29T05:06:31.356781Z","end":"2026-05-29T05:06:31.546557Z","steps":["trace[832645146] 'agreement among raft nodes before linearized reading' (duration: 189.360246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:31.546732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.356223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T05:06:31.546814Z","caller":"traceutil/trace.go:171","msg":"trace[938187967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"191.532878ms","start":"2026-05-29T05:06:31.355262Z","end":"2026-05-29T05:06:31.546795Z","steps":["trace[938187967] 'agreement among raft nodes before linearized reading' (duration: 190.881122ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:45.103136Z","caller":"traceutil/trace.go:171","msg":"trace[1101257160] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"139.951642ms","start":"2026-05-29T05:06:44.963155Z","end":"2026-05-29T05:06:45.103107Z","steps":["trace[1101257160] 'process raft request' (duration: 139.778688ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:47.327612Z","caller":"traceutil/trace.go:171","msg":"trace[1983417138] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"126.111234ms","start":"2026-05-29T05:06:47.201475Z","end":"2026-05-29T05:06:47.327586Z","steps":["trace[1983417138] 'read index received' (duration: 125.92435ms)","trace[1983417138] 'applied index is now lower than readState.Index' (duration: 185.914µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:06:47.32767Z","caller":"traceutil/trace.go:171","msg":"trace[646208000] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"216.139058ms","start":"2026-05-29T05:06:47.111498Z","end":"2026-05-29T05:06:47.327637Z","steps":["trace[646208000] 'process raft request' (duration: 215.926523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:47.327748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.260938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:06:47.327848Z","caller":"traceutil/trace.go:171","msg":"trace[2003340782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"126.370111ms","start":"2026-05-29T05:06:47.201409Z","end":"2026-05-29T05:06:47.327779Z","steps":["trace[2003340782] 'agreement among raft nodes before linearized reading' (duration: 126.287849ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:47.758274Z","caller":"traceutil/trace.go:171","msg":"trace[1999730504] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"130.449948ms","start":"2026-05-29T05:06:47.627795Z","end":"2026-05-29T05:06:47.758245Z","steps":["trace[1999730504] 'read index received' (duration: 130.184492ms)","trace[1999730504] 'applied index is now lower than readState.Index' (duration: 264.086µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:06:47.758582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.749775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:06:47.758659Z","caller":"traceutil/trace.go:171","msg":"trace[402909545] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:760; }","duration":"130.852427ms","start":"2026-05-29T05:06:47.627789Z","end":"2026-05-29T05:06:47.758641Z","steps":["trace[402909545] 'agreement among raft nodes before linearized reading' (duration: 130.699564ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:47.75858Z","caller":"traceutil/trace.go:171","msg":"trace[425722619] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"175.913119ms","start":"2026-05-29T05:06:47.582643Z","end":"2026-05-29T05:06:47.758556Z","steps":["trace[425722619] 'process raft request' (duration: 175.456148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:47.75879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.306394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:06:47.75885Z","caller":"traceutil/trace.go:171","msg":"trace[1283440589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"130.430897ms","start":"2026-05-29T05:06:47.628404Z","end":"2026-05-29T05:06:47.758835Z","steps":["trace[1283440589] 'agreement among raft nodes before linearized reading' (duration: 130.240992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:47.75914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.86525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T05:06:47.759153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.372851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T05:06:47.759179Z","caller":"traceutil/trace.go:171","msg":"trace[1033739477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"128.917922ms","start":"2026-05-29T05:06:47.630248Z","end":"2026-05-29T05:06:47.759166Z","steps":["trace[1033739477] 'agreement among raft nodes before linearized reading' (duration: 128.820919ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:06:47.759212Z","caller":"traceutil/trace.go:171","msg":"trace[761210601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"126.437063ms","start":"2026-05-29T05:06:47.632748Z","end":"2026-05-29T05:06:47.759185Z","steps":["trace[761210601] 'agreement among raft nodes before linearized reading' (duration: 126.30421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:06:47.760042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.117549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:06:47.760211Z","caller":"traceutil/trace.go:171","msg":"trace[1126864427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"127.367495ms","start":"2026-05-29T05:06:47.632795Z","end":"2026-05-29T05:06:47.760163Z","steps":["trace[1126864427] 'agreement among raft nodes before linearized reading' (duration: 126.764701ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:07:23.316185Z","caller":"traceutil/trace.go:171","msg":"trace[1654176306] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"481.604981ms","start":"2026-05-29T05:07:22.834554Z","end":"2026-05-29T05:07:23.316159Z","steps":["trace[1654176306] 'process raft request' (duration: 481.460037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:23.31636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:22.834534Z","time spent":"481.741655ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:07:23.539967Z","caller":"traceutil/trace.go:171","msg":"trace[1504410332] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1016; }","duration":"339.671052ms","start":"2026-05-29T05:07:23.200269Z","end":"2026-05-29T05:07:23.53994Z","steps":["trace[1504410332] 'read index received' (duration: 115.779871ms)","trace[1504410332] 'applied index is now lower than readState.Index' (duration: 223.890091ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:07:23.54004Z","caller":"traceutil/trace.go:171","msg":"trace[949022963] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"469.864794ms","start":"2026-05-29T05:07:23.070144Z","end":"2026-05-29T05:07:23.540009Z","steps":["trace[949022963] 'process raft request' (duration: 469.506116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:23.540104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.812535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:07:23.540144Z","caller":"traceutil/trace.go:171","msg":"trace[998901110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"339.874257ms","start":"2026-05-29T05:07:23.200258Z","end":"2026-05-29T05:07:23.540132Z","steps":["trace[998901110] 'agreement among raft nodes before linearized reading' (duration: 339.782915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:23.540174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:23.200207Z","time spent":"339.960329ms","remote":"127.0.0.1:35594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:07:23.540177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:23.070118Z","time spent":"469.979547ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:07:24.053397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.840237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:07:24.053519Z","caller":"traceutil/trace.go:171","msg":"trace[1230380111] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"105.117859ms","start":"2026-05-29T05:07:23.948382Z","end":"2026-05-29T05:07:24.0535Z","steps":["trace[1230380111] 'read index received' (duration: 66.541µs)","trace[1230380111] 'applied index is now lower than readState.Index' (duration: 105.049498ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:07:24.053571Z","caller":"traceutil/trace.go:171","msg":"trace[986115243] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"502.426841ms","start":"2026-05-29T05:07:23.551089Z","end":"2026-05-29T05:07:24.053516Z","steps":["trace[986115243] 'process raft request' (duration: 343.188494ms)","trace[986115243] 'compare' (duration: 158.599712ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:07:24.053688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.301944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:07:24.05374Z","caller":"traceutil/trace.go:171","msg":"trace[340472268] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"105.419887ms","start":"2026-05-29T05:07:23.948308Z","end":"2026-05-29T05:07:24.053728Z","steps":["trace[340472268] 'agreement among raft nodes before linearized reading' (duration: 105.275173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:24.053735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:23.551068Z","time spent":"502.574065ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:07:31.459345Z","caller":"traceutil/trace.go:171","msg":"trace[505134802] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"117.072892ms","start":"2026-05-29T05:07:31.342252Z","end":"2026-05-29T05:07:31.459325Z","steps":["trace[505134802] 'process raft request' (duration: 116.955099ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:07:37.634381Z","caller":"traceutil/trace.go:171","msg":"trace[586087094] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"128.266403ms","start":"2026-05-29T05:07:37.506083Z","end":"2026-05-29T05:07:37.634349Z","steps":["trace[586087094] 'process raft request' (duration: 128.050948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:46.664741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899558538940444,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:07:46.767402Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.082108397s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:07:46.767716Z","caller":"traceutil/trace.go:171","msg":"trace[1129699590] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"603.114007ms","start":"2026-05-29T05:07:46.164585Z","end":"2026-05-29T05:07:46.767699Z","steps":["trace[1129699590] 'read index received' (duration: 602.899222ms)","trace[1129699590] 'applied index is now lower than readState.Index' (duration: 213.955µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:07:46.767752Z","caller":"traceutil/trace.go:171","msg":"trace[1593373704] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"1.082543928s","start":"2026-05-29T05:07:45.685193Z","end":"2026-05-29T05:07:46.767737Z","steps":["trace[1593373704] 'process raft request' (duration: 1.082384584s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:46.767819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:07:46.767852Z","caller":"traceutil/trace.go:171","msg":"trace[467088381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1049; }","duration":"565.941081ms","start":"2026-05-29T05:07:46.2019Z","end":"2026-05-29T05:07:46.767841Z","steps":["trace[467088381] 'agreement among raft nodes before linearized reading' (duration: 565.887769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:46.767889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:46.20183Z","time spent":"566.048763ms","remote":"127.0.0.1:35594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:07:46.767967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:45.685175Z","time spent":"1.082609089s","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:07:46.768011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.028419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:07:46.768093Z","caller":"traceutil/trace.go:171","msg":"trace[1336005085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1049; }","duration":"467.167972ms","start":"2026-05-29T05:07:46.30091Z","end":"2026-05-29T05:07:46.768078Z","steps":["trace[1336005085] 'agreement among raft nodes before linearized reading' (duration: 466.990238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:46.768156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:46.300886Z","time spent":"467.261335ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:07:46.768195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.553343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T05:07:46.768073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.144457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:07:46.768252Z","caller":"traceutil/trace.go:171","msg":"trace[1724118561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1049; }","duration":"445.641995ms","start":"2026-05-29T05:07:46.322597Z","end":"2026-05-29T05:07:46.768239Z","steps":["trace[1724118561] 'agreement among raft nodes before linearized reading' (duration: 445.334098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:46.768288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:46.322577Z","time spent":"445.702806ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T05:07:46.768295Z","caller":"traceutil/trace.go:171","msg":"trace[354057789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"446.416663ms","start":"2026-05-29T05:07:46.321853Z","end":"2026-05-29T05:07:46.76827Z","steps":["trace[354057789] 'agreement among raft nodes before linearized reading' (duration: 446.031514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:46.768268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.745401ms","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-05-29T05:07:46.768328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:46.321839Z","time spent":"446.480985ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T05:07:46.76836Z","caller":"traceutil/trace.go:171","msg":"trace[737121175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1049; }","duration":"530.886884ms","start":"2026-05-29T05:07:46.237446Z","end":"2026-05-29T05:07:46.768333Z","steps":["trace[737121175] 'agreement among raft nodes before linearized reading' (duration: 530.602637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:46.768514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:46.237428Z","time spent":"531.063219ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:07:46.768423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.847934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T05:07:46.76865Z","caller":"traceutil/trace.go:171","msg":"trace[1315625685] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1049; }","duration":"604.11991ms","start":"2026-05-29T05:07:46.164523Z","end":"2026-05-29T05:07:46.768642Z","steps":["trace[1315625685] 'agreement among raft nodes before linearized reading' (duration: 603.24818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:46.768677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:46.164499Z","time spent":"604.169022ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T05:07:50.155262Z","caller":"traceutil/trace.go:171","msg":"trace[1926872530] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"280.265337ms","start":"2026-05-29T05:07:49.874963Z","end":"2026-05-29T05:07:50.155228Z","steps":["trace[1926872530] 'process raft request' (duration: 218.316586ms)","trace[1926872530] 'compare' (duration: 61.715485ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:07:51.336756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.431091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:07:51.336813Z","caller":"traceutil/trace.go:171","msg":"trace[499714421] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1061; }","duration":"994.532634ms","start":"2026-05-29T05:07:50.342266Z","end":"2026-05-29T05:07:51.336799Z","steps":["trace[499714421] 'count revisions from in-memory index tree' (duration: 994.341989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:51.336804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.099002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:07:51.336905Z","caller":"traceutil/trace.go:171","msg":"trace[974971162] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1061; }","duration":"907.258626ms","start":"2026-05-29T05:07:50.429623Z","end":"2026-05-29T05:07:51.336881Z","steps":["trace[974971162] 'count revisions from in-memory index tree' (duration: 906.980109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:51.336949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.707054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:07:51.336973Z","caller":"traceutil/trace.go:171","msg":"trace[327488112] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1061; }","duration":"926.770636ms","start":"2026-05-29T05:07:50.410196Z","end":"2026-05-29T05:07:51.336966Z","steps":["trace[327488112] 'count revisions from in-memory index tree' (duration: 926.5269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:51.336964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:50.429603Z","time spent":"907.345088ms","remote":"127.0.0.1:35982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:07:51.336994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:50.410175Z","time spent":"926.812477ms","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:07:51.336847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:50.34224Z","time spent":"994.594004ms","remote":"127.0.0.1:58378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:07:51.378053Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899558538940482,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:07:51.388166Z","caller":"traceutil/trace.go:171","msg":"trace[2139117039] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"584.534729ms","start":"2026-05-29T05:07:50.803611Z","end":"2026-05-29T05:07:51.388146Z","steps":["trace[2139117039] 'process raft request' (duration: 584.384895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:51.38829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:50.803585Z","time spent":"584.62371ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:07:51.876257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.453377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:07:51.876371Z","caller":"traceutil/trace.go:171","msg":"trace[2132927608] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1108; }","duration":"999.148562ms","start":"2026-05-29T05:07:50.877206Z","end":"2026-05-29T05:07:51.876355Z","steps":["trace[2132927608] 'read index received' (duration: 510.797341ms)","trace[2132927608] 'applied index is now lower than readState.Index' (duration: 488.350071ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:07:51.876416Z","caller":"traceutil/trace.go:171","msg":"trace[431293697] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"650.008172ms","start":"2026-05-29T05:07:51.226374Z","end":"2026-05-29T05:07:51.876382Z","steps":["trace[431293697] 'process raft request' (duration: 524.338589ms)","trace[431293697] 'compare' (duration: 124.638438ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:07:51.876541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.339056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:07:51.876571Z","caller":"traceutil/trace.go:171","msg":"trace[1284931612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1063; }","duration":"999.406158ms","start":"2026-05-29T05:07:50.877155Z","end":"2026-05-29T05:07:51.876561Z","steps":["trace[1284931612] 'agreement among raft nodes before linearized reading' (duration: 999.284645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:51.876565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.184828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T05:07:51.876588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:51.226351Z","time spent":"650.135745ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:07:51.876601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:50.877133Z","time spent":"999.46147ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:07:51.876608Z","caller":"traceutil/trace.go:171","msg":"trace[1614982217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1063; }","duration":"992.253109ms","start":"2026-05-29T05:07:50.884343Z","end":"2026-05-29T05:07:51.876596Z","steps":["trace[1614982217] 'agreement among raft nodes before linearized reading' (duration: 992.138136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:51.876652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:50.884332Z","time spent":"992.309361ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:07:51.876781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.02638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:07:51.876809Z","caller":"traceutil/trace.go:171","msg":"trace[88813819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1063; }","duration":"991.074321ms","start":"2026-05-29T05:07:50.885727Z","end":"2026-05-29T05:07:51.876801Z","steps":["trace[88813819] 'agreement among raft nodes before linearized reading' (duration: 990.99353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:51.876838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:50.885716Z","time spent":"991.112842ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:07:51.87687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.164104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:07:51.876905Z","caller":"traceutil/trace.go:171","msg":"trace[1072128654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1063; }","duration":"991.226935ms","start":"2026-05-29T05:07:50.885668Z","end":"2026-05-29T05:07:51.876895Z","steps":["trace[1072128654] 'agreement among raft nodes before linearized reading' (duration: 991.069011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:51.87693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.541793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:07:51.876933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:50.885656Z","time spent":"991.270575ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T05:07:51.876963Z","caller":"traceutil/trace.go:171","msg":"trace[1350371449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"675.603295ms","start":"2026-05-29T05:07:51.201345Z","end":"2026-05-29T05:07:51.876949Z","steps":["trace[1350371449] 'agreement among raft nodes before linearized reading' (duration: 675.554464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:51.876874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.424643ms","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-05-29T05:07:51.876995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:51.201326Z","time spent":"675.658397ms","remote":"127.0.0.1:35594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T05:07:51.877015Z","caller":"traceutil/trace.go:171","msg":"trace[1803214206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"992.604337ms","start":"2026-05-29T05:07:50.884403Z","end":"2026-05-29T05:07:51.877007Z","steps":["trace[1803214206] 'agreement among raft nodes before linearized reading' (duration: 992.408503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:07:51.877045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:07:50.884383Z","time spent":"992.654619ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:08:10.218539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899558538940735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:08:10.719385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899558538940735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:08:11.220366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899558538940735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:08:11.720861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899558538940735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:08:12.201765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000462943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-29T05:08:12.201858Z","caller":"traceutil/trace.go:171","msg":"trace[1142466903] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000613737s","start":"2026-05-29T05:08:10.201225Z","end":"2026-05-29T05:08:12.201838Z","steps":["trace[1142466903] 'agreement among raft nodes before linearized reading' (duration: 2.000494274s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:12.201921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:10.201208Z","time spent":"2.000700359s","remote":"127.0.0.1:35594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/29 05:08:12 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T05:08:12.221252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899558538940735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:08:12.266141Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.770950555s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:08:12.266563Z","caller":"traceutil/trace.go:171","msg":"trace[206984763] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"2.771394115s","start":"2026-05-29T05:08:09.495121Z","end":"2026-05-29T05:08:12.266515Z","steps":["trace[206984763] 'process raft request' (duration: 2.771268042s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:12.266774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:09.495102Z","time spent":"2.771526229s","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:08:12.282038Z","caller":"traceutil/trace.go:171","msg":"trace[1889461194] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1223; }","duration":"2.564348045s","start":"2026-05-29T05:08:09.717671Z","end":"2026-05-29T05:08:12.282019Z","steps":["trace[1889461194] 'read index received' (duration: 2.548595884s)","trace[1889461194] 'applied index is now lower than readState.Index' (duration: 15.751071ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:08:12.282308Z","caller":"traceutil/trace.go:171","msg":"trace[558418477] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"169.895615ms","start":"2026-05-29T05:08:12.112398Z","end":"2026-05-29T05:08:12.282294Z","steps":["trace[558418477] 'process raft request' (duration: 169.504346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:12.28258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.564895758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-29T05:08:12.282618Z","caller":"traceutil/trace.go:171","msg":"trace[692061045] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:1173; }","duration":"2.564994451s","start":"2026-05-29T05:08:09.717614Z","end":"2026-05-29T05:08:12.282608Z","steps":["trace[692061045] 'agreement among raft nodes before linearized reading' (duration: 2.564857488s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:12.282652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:09.717594Z","time spent":"2.565050692s","remote":"127.0.0.1:35652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"warn","ts":"2026-05-29T05:08:12.282854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.318116461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:08:12.282889Z","caller":"traceutil/trace.go:171","msg":"trace[382422899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1173; }","duration":"2.318186323s","start":"2026-05-29T05:08:09.964695Z","end":"2026-05-29T05:08:12.282881Z","steps":["trace[382422899] 'agreement among raft nodes before linearized reading' (duration: 2.318101641s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:12.282914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:09.964677Z","time spent":"2.318231084s","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:08:12.283215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.971014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:08:12.283244Z","caller":"traceutil/trace.go:171","msg":"trace[1344898863] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1173; }","duration":"956.028926ms","start":"2026-05-29T05:08:11.327207Z","end":"2026-05-29T05:08:12.283236Z","steps":["trace[1344898863] 'agreement among raft nodes before linearized reading' (duration: 955.980164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:12.28327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:11.327192Z","time spent":"956.071236ms","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:08:12.283458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.206544221s","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-05-29T05:08:12.283548Z","caller":"traceutil/trace.go:171","msg":"trace[1149015220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"2.206636613s","start":"2026-05-29T05:08:10.076887Z","end":"2026-05-29T05:08:12.283524Z","steps":["trace[1149015220] 'agreement among raft nodes before linearized reading' (duration: 2.20650695s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:12.283583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:10.076876Z","time spent":"2.206700785s","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:08:12.283724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.206810777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T05:08:12.28379Z","caller":"traceutil/trace.go:171","msg":"trace[803172945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1173; }","duration":"2.20691378s","start":"2026-05-29T05:08:10.076857Z","end":"2026-05-29T05:08:12.28377Z","steps":["trace[803172945] 'agreement among raft nodes before linearized reading' (duration: 2.206726386s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:12.283826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:10.076841Z","time spent":"2.206977001s","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:08:12.284026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.207298739s","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-05-29T05:08:12.284052Z","caller":"traceutil/trace.go:171","msg":"trace[1206266099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1173; }","duration":"2.207361781s","start":"2026-05-29T05:08:10.076682Z","end":"2026-05-29T05:08:12.284044Z","steps":["trace[1206266099] 'agreement among raft nodes before linearized reading' (duration: 2.207289519s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:12.284082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:10.07666Z","time spent":"2.207409871s","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:08:12.28415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.264014386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T05:08:12.284183Z","caller":"traceutil/trace.go:171","msg":"trace[493949395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1173; }","duration":"2.264100028s","start":"2026-05-29T05:08:10.020074Z","end":"2026-05-29T05:08:12.284174Z","steps":["trace[493949395] 'agreement among raft nodes before linearized reading' (duration: 2.264010936s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:12.284211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:10.020052Z","time spent":"2.264152469s","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:08:14.261054Z","caller":"traceutil/trace.go:171","msg":"trace[234099269] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"183.903355ms","start":"2026-05-29T05:08:14.077131Z","end":"2026-05-29T05:08:14.261035Z","steps":["trace[234099269] 'read index received' (duration: 183.67008ms)","trace[234099269] 'applied index is now lower than readState.Index' (duration: 232.245µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:08:14.261149Z","caller":"traceutil/trace.go:171","msg":"trace[100687366] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"197.414343ms","start":"2026-05-29T05:08:14.063702Z","end":"2026-05-29T05:08:14.261117Z","steps":["trace[100687366] 'process raft request' (duration: 197.214809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:14.261223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.079509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:08:14.261249Z","caller":"traceutil/trace.go:171","msg":"trace[1905999476] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1181; }","duration":"184.156721ms","start":"2026-05-29T05:08:14.077087Z","end":"2026-05-29T05:08:14.261244Z","steps":["trace[1905999476] 'agreement among raft nodes before linearized reading' (duration: 184.052009ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:24.427459Z","caller":"traceutil/trace.go:171","msg":"trace[2043976721] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"127.658429ms","start":"2026-05-29T05:08:24.299784Z","end":"2026-05-29T05:08:24.427443Z","steps":["trace[2043976721] 'process raft request' (duration: 127.454734ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:24.53582Z","caller":"traceutil/trace.go:171","msg":"trace[1566026771] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"104.754739ms","start":"2026-05-29T05:08:24.431036Z","end":"2026-05-29T05:08:24.535791Z","steps":["trace[1566026771] 'process raft request' (duration: 27.275622ms)","trace[1566026771] 'compare' (duration: 77.366184ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:08:24.578632Z","caller":"traceutil/trace.go:171","msg":"trace[1951253490] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"145.711304ms","start":"2026-05-29T05:08:24.4327Z","end":"2026-05-29T05:08:24.578412Z","steps":["trace[1951253490] 'process raft request' (duration: 145.556501ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:24.578865Z","caller":"traceutil/trace.go:171","msg":"trace[250001260] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"146.080714ms","start":"2026-05-29T05:08:24.432763Z","end":"2026-05-29T05:08:24.578844Z","steps":["trace[250001260] 'process raft request' (duration: 145.599962ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:24.578894Z","caller":"traceutil/trace.go:171","msg":"trace[1082347694] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"145.54005ms","start":"2026-05-29T05:08:24.433338Z","end":"2026-05-29T05:08:24.578878Z","steps":["trace[1082347694] 'process raft request' (duration: 145.487129ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:24.579002Z","caller":"traceutil/trace.go:171","msg":"trace[28225091] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"146.141414ms","start":"2026-05-29T05:08:24.43278Z","end":"2026-05-29T05:08:24.578922Z","steps":["trace[28225091] 'process raft request' (duration: 145.979771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:29.093581Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899558538940905,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:08:29.594537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899558538940905,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:08:29.649974Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.100550261s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:08:29.650359Z","caller":"traceutil/trace.go:171","msg":"trace[1143163754] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"1.057232031s","start":"2026-05-29T05:08:28.593109Z","end":"2026-05-29T05:08:29.650341Z","steps":["trace[1143163754] 'read index received' (duration: 1.057117928s)","trace[1143163754] 'applied index is now lower than readState.Index' (duration: 113.323µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:08:29.65042Z","caller":"traceutil/trace.go:171","msg":"trace[1075496033] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"1.101094045s","start":"2026-05-29T05:08:28.549318Z","end":"2026-05-29T05:08:29.650412Z","steps":["trace[1075496033] 'process raft request' (duration: 1.100854169s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:29.650518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:28.549303Z","time spent":"1.101141266s","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:08:29.650552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057420035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:08:29.650611Z","caller":"traceutil/trace.go:171","msg":"trace[191462766] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1248; }","duration":"1.057521897s","start":"2026-05-29T05:08:28.593076Z","end":"2026-05-29T05:08:29.650598Z","steps":["trace[191462766] 'agreement among raft nodes before linearized reading' (duration: 1.057344553s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:29.65064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:28.59306Z","time spent":"1.057575429s","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:08:29.65082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056992705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T05:08:29.650844Z","caller":"traceutil/trace.go:171","msg":"trace[1295376419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1248; }","duration":"1.057025476s","start":"2026-05-29T05:08:28.593812Z","end":"2026-05-29T05:08:29.650837Z","steps":["trace[1295376419] 'agreement among raft nodes before linearized reading' (duration: 1.056967224s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:29.650858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:28.593807Z","time spent":"1.057046306s","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:08:29.650912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.528876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:08:29.650948Z","caller":"traceutil/trace.go:171","msg":"trace[713984988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"449.601388ms","start":"2026-05-29T05:08:29.201337Z","end":"2026-05-29T05:08:29.650939Z","steps":["trace[713984988] 'agreement among raft nodes before linearized reading' (duration: 449.546607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:29.650969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:29.201316Z","time spent":"449.647999ms","remote":"127.0.0.1:35594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:08:29.651017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.619464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:08:29.651036Z","caller":"traceutil/trace.go:171","msg":"trace[351365868] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1248; }","duration":"627.674605ms","start":"2026-05-29T05:08:29.023357Z","end":"2026-05-29T05:08:29.651031Z","steps":["trace[351365868] 'agreement among raft nodes before linearized reading' (duration: 627.641875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:29.65105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:29.023338Z","time spent":"627.709216ms","remote":"127.0.0.1:35844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:08:29.651132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.92616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:08:29.651158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056650197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:08:29.651173Z","caller":"traceutil/trace.go:171","msg":"trace[1853322870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1248; }","duration":"1.056699519s","start":"2026-05-29T05:08:28.59447Z","end":"2026-05-29T05:08:29.651169Z","steps":["trace[1853322870] 'agreement among raft nodes before linearized reading' (duration: 1.056651828s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:29.65117Z","caller":"traceutil/trace.go:171","msg":"trace[537332293] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1248; }","duration":"327.993772ms","start":"2026-05-29T05:08:29.323167Z","end":"2026-05-29T05:08:29.651161Z","steps":["trace[537332293] 'agreement among raft nodes before linearized reading' (duration: 327.93448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:29.651188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:28.59445Z","time spent":"1.056735189s","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:08:29.651196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:29.323153Z","time spent":"328.036502ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:08:29.651241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057390974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:08:29.651257Z","caller":"traceutil/trace.go:171","msg":"trace[366368606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1248; }","duration":"1.057412975s","start":"2026-05-29T05:08:28.593841Z","end":"2026-05-29T05:08:29.651254Z","steps":["trace[366368606] 'agreement among raft nodes before linearized reading' (duration: 1.057376934s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:29.651271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:28.593836Z","time spent":"1.057432096s","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:08:29.937138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.518358ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-29T05:08:30.200973Z","caller":"traceutil/trace.go:171","msg":"trace[1165030040] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"104.732509ms","start":"2026-05-29T05:08:30.096218Z","end":"2026-05-29T05:08:30.20095Z","steps":["trace[1165030040] 'process raft request' (duration: 104.586485ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:30.803243Z","caller":"traceutil/trace.go:171","msg":"trace[1675567993] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"284.148548ms","start":"2026-05-29T05:08:30.519065Z","end":"2026-05-29T05:08:30.803213Z","steps":["trace[1675567993] 'process raft request' (duration: 283.968554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:31.318636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.376456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:08:31.318724Z","caller":"traceutil/trace.go:171","msg":"trace[44634380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"117.482229ms","start":"2026-05-29T05:08:31.201223Z","end":"2026-05-29T05:08:31.318705Z","steps":["trace[44634380] 'range keys from in-memory index tree' (duration: 117.284284ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:32.010437Z","caller":"traceutil/trace.go:171","msg":"trace[851502298] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"354.066084ms","start":"2026-05-29T05:08:31.656348Z","end":"2026-05-29T05:08:32.010414Z","steps":["trace[851502298] 'read index received' (duration: 353.88741ms)","trace[851502298] 'applied index is now lower than readState.Index' (duration: 177.804µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:08:32.010665Z","caller":"traceutil/trace.go:171","msg":"trace[868221178] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"509.500659ms","start":"2026-05-29T05:08:31.501152Z","end":"2026-05-29T05:08:32.010652Z","steps":["trace[868221178] 'process raft request' (duration: 509.143731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:32.01075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:31.501135Z","time spent":"509.56293ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:08:32.011032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.67798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:08:32.011064Z","caller":"traceutil/trace.go:171","msg":"trace[208300332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1257; }","duration":"354.754412ms","start":"2026-05-29T05:08:31.6563Z","end":"2026-05-29T05:08:32.011055Z","steps":["trace[208300332] 'agreement among raft nodes before linearized reading' (duration: 354.603908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:32.011092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:31.656271Z","time spent":"354.815272ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:08:32.011315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.750064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:08:32.011373Z","caller":"traceutil/trace.go:171","msg":"trace[452484886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1257; }","duration":"313.830737ms","start":"2026-05-29T05:08:31.697528Z","end":"2026-05-29T05:08:32.011359Z","steps":["trace[452484886] 'agreement among raft nodes before linearized reading' (duration: 313.700284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:32.011417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:31.697515Z","time spent":"313.887738ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:08:32.011445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.173741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:08:32.011504Z","caller":"traceutil/trace.go:171","msg":"trace[1522610667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"310.248982ms","start":"2026-05-29T05:08:31.701246Z","end":"2026-05-29T05:08:32.011495Z","steps":["trace[1522610667] 'agreement among raft nodes before linearized reading' (duration: 310.132629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:32.011532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:31.70124Z","time spent":"310.285933ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:08:32.011654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.961519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:08:32.011677Z","caller":"traceutil/trace.go:171","msg":"trace[686496930] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"311.02296ms","start":"2026-05-29T05:08:31.700646Z","end":"2026-05-29T05:08:32.011669Z","steps":["trace[686496930] 'agreement among raft nodes before linearized reading' (duration: 310.952138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:32.011702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:31.700625Z","time spent":"311.070622ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:08:32.011844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.766944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T05:08:32.011879Z","caller":"traceutil/trace.go:171","msg":"trace[206247537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1257; }","duration":"310.838016ms","start":"2026-05-29T05:08:31.701032Z","end":"2026-05-29T05:08:32.01187Z","steps":["trace[206247537] 'agreement among raft nodes before linearized reading' (duration: 310.761854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:32.011906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:31.701012Z","time spent":"310.885657ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T05:08:32.177616Z","caller":"traceutil/trace.go:171","msg":"trace[176702801] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"162.162322ms","start":"2026-05-29T05:08:32.015435Z","end":"2026-05-29T05:08:32.177597Z","steps":["trace[176702801] 'process raft request' (duration: 161.974088ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:32.178073Z","caller":"traceutil/trace.go:171","msg":"trace[381840770] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"162.540601ms","start":"2026-05-29T05:08:32.01552Z","end":"2026-05-29T05:08:32.17806Z","steps":["trace[381840770] 'process raft request' (duration: 162.433119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:32.178207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.645844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-29T05:08:32.178252Z","caller":"traceutil/trace.go:171","msg":"trace[1945730813] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1263; }","duration":"162.719235ms","start":"2026-05-29T05:08:32.015522Z","end":"2026-05-29T05:08:32.178241Z","steps":["trace[1945730813] 'agreement among raft nodes before linearized reading' (duration: 162.574462ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:32.178333Z","caller":"traceutil/trace.go:171","msg":"trace[389207540] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"162.534671ms","start":"2026-05-29T05:08:32.015788Z","end":"2026-05-29T05:08:32.178323Z","steps":["trace[389207540] 'process raft request' (duration: 162.205344ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:32.178366Z","caller":"traceutil/trace.go:171","msg":"trace[1079938226] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"162.859279ms","start":"2026-05-29T05:08:32.015497Z","end":"2026-05-29T05:08:32.178356Z","steps":["trace[1079938226] 'process raft request' (duration: 162.04276ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:32.178071Z","caller":"traceutil/trace.go:171","msg":"trace[468285039] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1314; }","duration":"162.512911ms","start":"2026-05-29T05:08:32.015544Z","end":"2026-05-29T05:08:32.178057Z","steps":["trace[468285039] 'read index received' (duration: 24.127419ms)","trace[468285039] 'applied index is now lower than readState.Index' (duration: 138.384822ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:08:32.1785Z","caller":"traceutil/trace.go:171","msg":"trace[2075609604] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"161.22665ms","start":"2026-05-29T05:08:32.017265Z","end":"2026-05-29T05:08:32.178492Z","steps":["trace[2075609604] 'process raft request' (duration: 160.760129ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:32.233185Z","caller":"traceutil/trace.go:171","msg":"trace[2086544881] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"181.110269ms","start":"2026-05-29T05:08:32.052052Z","end":"2026-05-29T05:08:32.233162Z","steps":["trace[2086544881] 'process raft request' (duration: 181.009306ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:32.960014Z","caller":"traceutil/trace.go:171","msg":"trace[1930651544] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"221.41977ms","start":"2026-05-29T05:08:32.738572Z","end":"2026-05-29T05:08:32.959991Z","steps":["trace[1930651544] 'process raft request' (duration: 221.271156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:33.348592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.600975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:08:33.348703Z","caller":"traceutil/trace.go:171","msg":"trace[1665004996] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"147.276671ms","start":"2026-05-29T05:08:33.20141Z","end":"2026-05-29T05:08:33.348687Z","steps":["trace[1665004996] 'read index received' (duration: 24.121µs)","trace[1665004996] 'applied index is now lower than readState.Index' (duration: 147.25141ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:08:33.348762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.348853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:08:33.348792Z","caller":"traceutil/trace.go:171","msg":"trace[232957824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1268; }","duration":"147.414075ms","start":"2026-05-29T05:08:33.20137Z","end":"2026-05-29T05:08:33.348784Z","steps":["trace[232957824] 'agreement among raft nodes before linearized reading' (duration: 147.361664ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:08:33.348877Z","caller":"traceutil/trace.go:171","msg":"trace[2093389930] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"539.084237ms","start":"2026-05-29T05:08:32.809756Z","end":"2026-05-29T05:08:33.348841Z","steps":["trace[2093389930] 'process raft request' (duration: 199.148774ms)","trace[2093389930] 'compare' (duration: 339.460192ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:08:33.34905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:32.80973Z","time spent":"539.22317ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:08:39.760306Z","caller":"traceutil/trace.go:171","msg":"trace[384366125] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"394.163533ms","start":"2026-05-29T05:08:39.366113Z","end":"2026-05-29T05:08:39.760277Z","steps":["trace[384366125] 'process raft request' (duration: 394.00795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:08:39.760459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:08:39.366025Z","time spent":"394.355898ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:08:39.910168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.176972ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-29T05:08:43.881333Z","caller":"traceutil/trace.go:171","msg":"trace[1744484923] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"110.204398ms","start":"2026-05-29T05:08:43.771108Z","end":"2026-05-29T05:08:43.881312Z","steps":["trace[1744484923] 'process raft request' (duration: 110.096236ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:00.450864Z","caller":"traceutil/trace.go:171","msg":"trace[435501466] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"106.407289ms","start":"2026-05-29T05:09:00.34443Z","end":"2026-05-29T05:09:00.450838Z","steps":["trace[435501466] 'process raft request' (duration: 38.335474ms)","trace[435501466] 'compare' (duration: 67.910911ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:09:00.451186Z","caller":"traceutil/trace.go:171","msg":"trace[805469281] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"105.820335ms","start":"2026-05-29T05:09:00.345335Z","end":"2026-05-29T05:09:00.451156Z","steps":["trace[805469281] 'process raft request' (duration: 105.466776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:42.740521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.758326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:09:42.740615Z","caller":"traceutil/trace.go:171","msg":"trace[999977952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1610; }","duration":"100.933119ms","start":"2026-05-29T05:09:42.639663Z","end":"2026-05-29T05:09:42.740597Z","steps":["trace[999977952] 'range keys from in-memory index tree' (duration: 100.579641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:42.740637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.146741ms","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-05-29T05:09:42.740699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.345747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:09:42.740739Z","caller":"traceutil/trace.go:171","msg":"trace[1795166936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1610; }","duration":"156.292245ms","start":"2026-05-29T05:09:42.584419Z","end":"2026-05-29T05:09:42.740711Z","steps":["trace[1795166936] 'range keys from in-memory index tree' (duration: 155.953857ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:09:42.740785Z","caller":"traceutil/trace.go:171","msg":"trace[2039744886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1610; }","duration":"173.47766ms","start":"2026-05-29T05:09:42.567286Z","end":"2026-05-29T05:09:42.740764Z","steps":["trace[2039744886] 'range keys from in-memory index tree' (duration: 173.182203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:09:42.740831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.980679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T05:09:42.740887Z","caller":"traceutil/trace.go:171","msg":"trace[490151867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1610; }","duration":"162.063151ms","start":"2026-05-29T05:09:42.578809Z","end":"2026-05-29T05:09:42.740872Z","steps":["trace[490151867] 'range keys from in-memory index tree' (duration: 161.835826ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:11:48.224844Z","caller":"traceutil/trace.go:171","msg":"trace[1647525243] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"107.127756ms","start":"2026-05-29T05:11:48.117688Z","end":"2026-05-29T05:11:48.224816Z","steps":["trace[1647525243] 'process raft request' (duration: 106.8528ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:11:49.482923Z","caller":"traceutil/trace.go:171","msg":"trace[1469497053] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"147.533139ms","start":"2026-05-29T05:11:49.335364Z","end":"2026-05-29T05:11:49.482897Z","steps":["trace[1469497053] 'process raft request' (duration: 108.873097ms)","trace[1469497053] 'compare' (duration: 38.472307ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:12:21.34922Z","caller":"traceutil/trace.go:171","msg":"trace[1655491875] linearizableReadLoop","detail":"{readStateIndex:2469; appliedIndex:2468; }","duration":"148.225704ms","start":"2026-05-29T05:12:21.200973Z","end":"2026-05-29T05:12:21.349198Z","steps":["trace[1655491875] 'read index received' (duration: 148.014129ms)","trace[1655491875] 'applied index is now lower than readState.Index' (duration: 210.645µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:12:21.349341Z","caller":"traceutil/trace.go:171","msg":"trace[1328264494] transaction","detail":"{read_only:false; response_revision:2365; number_of_response:1; }","duration":"632.203025ms","start":"2026-05-29T05:12:20.717128Z","end":"2026-05-29T05:12:21.349332Z","steps":["trace[1328264494] 'process raft request' (duration: 631.930719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:12:21.34944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:12:20.717106Z","time spent":"632.261537ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:12:21.349728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.547105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-05-29T05:12:21.349749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.775018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:12:21.349819Z","caller":"traceutil/trace.go:171","msg":"trace[761978979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2365; }","duration":"148.883811ms","start":"2026-05-29T05:12:21.200921Z","end":"2026-05-29T05:12:21.349805Z","steps":["trace[761978979] 'agreement among raft nodes before linearized reading' (duration: 148.783168ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:12:21.349818Z","caller":"traceutil/trace.go:171","msg":"trace[423957175] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2365; }","duration":"132.695438ms","start":"2026-05-29T05:12:21.217102Z","end":"2026-05-29T05:12:21.349797Z","steps":["trace[423957175] 'agreement among raft nodes before linearized reading' (duration: 132.459303ms)"],"step_count":1}