{"level":"warn","ts":"2026-04-07T15:11:22.299518Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T15:11:22.299665Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.90:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.90:2380","--initial-cluster=instance=https://199.19.213.90:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.90:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.90:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T15:11:22.299762Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T15:11:22.299782Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-04-07T15:11:22.299813Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T15:11:22.300833Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"]} {"level":"info","ts":"2026-04-07T15:11:22.300955Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.90:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T15:11:22.305784Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.533552ms"} {"level":"info","ts":"2026-04-07T15:11:22.313351Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b21fdc9c735cc27","cluster-id":"77163f3b44f8f1db"} {"level":"info","ts":"2026-04-07T15:11:22.313477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T15:11:22.313503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 0"} {"level":"info","ts":"2026-04-07T15:11:22.313523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b21fdc9c735cc27 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T15:11:22.313536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 1"} {"level":"info","ts":"2026-04-07T15:11:22.313592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"warn","ts":"2026-04-07T15:11:22.321319Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T15:11:22.323202Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T15:11:22.324458Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T15:11:22.326785Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b21fdc9c735cc27","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T15:11:22.327027Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b21fdc9c735cc27","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T15:11:22.327332Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T15:11:22.327448Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T15:11:22.327466Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T15:11:22.328288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"info","ts":"2026-04-07T15:11:22.329702Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","added-peer-id":"2b21fdc9c735cc27","added-peer-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-04-07T15:11:22.331546Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T15:11:22.331874Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b21fdc9c735cc27","initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T15:11:22.331918Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T15:11:22.332082Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-04-07T15:11:22.332104Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-04-07T15:11:22.61398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T15:11:22.614055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T15:11:22.614099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgPreVoteResp from 2b21fdc9c735cc27 at term 1"} {"level":"info","ts":"2026-04-07T15:11:22.614122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became candidate at term 2"} {"level":"info","ts":"2026-04-07T15:11:22.614133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgVoteResp from 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-04-07T15:11:22.614152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became leader at term 2"} {"level":"info","ts":"2026-04-07T15:11:22.614167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b21fdc9c735cc27 elected leader 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-04-07T15:11:22.615382Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b21fdc9c735cc27","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.90:2379]}","request-path":"/0/members/2b21fdc9c735cc27/attributes","cluster-id":"77163f3b44f8f1db","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T15:11:22.615612Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:11:22.615678Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:11:22.615807Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T15:11:22.615887Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T15:11:22.615973Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:11:22.616759Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:11:22.616921Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:11:22.617035Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:11:22.618039Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T15:11:22.620701Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.90:2379"} {"level":"warn","ts":"2026-04-07T15:11:25.637992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.932167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:11:25.638284Z","caller":"traceutil/trace.go:171","msg":"trace[263326438] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"505.139147ms","start":"2026-04-07T15:11:25.13308Z","end":"2026-04-07T15:11:25.638219Z","steps":["trace[263326438] 'process raft request' (duration: 327.191337ms)","trace[263326438] 'compare' (duration: 176.62265ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:11:25.638227Z","caller":"traceutil/trace.go:171","msg":"trace[1588796874] linearizableReadLoop","detail":"{readStateIndex:146; appliedIndex:145; }","duration":"118.475187ms","start":"2026-04-07T15:11:25.519689Z","end":"2026-04-07T15:11:25.638164Z","steps":["trace[1588796874] 'read index received' (duration: 46.601µs)","trace[1588796874] 'applied index is now lower than readState.Index' (duration: 118.426796ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:11:25.638504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:25.133068Z","time spent":"505.338761ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":666,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:11:25.638708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.932387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:11:25.638828Z","caller":"traceutil/trace.go:171","msg":"trace[382684503] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:141; }","duration":"119.184233ms","start":"2026-04-07T15:11:25.519615Z","end":"2026-04-07T15:11:25.638799Z","steps":["trace[382684503] 'agreement among raft nodes before linearized reading' (duration: 118.767654ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:27.378747Z","caller":"traceutil/trace.go:171","msg":"trace[265984911] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:255; }","duration":"323.409781ms","start":"2026-04-07T15:11:27.055293Z","end":"2026-04-07T15:11:27.378702Z","steps":["trace[265984911] 'read index received' (duration: 322.835659ms)","trace[265984911] 'applied index is now lower than readState.Index' (duration: 572.432µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:11:27.378895Z","caller":"traceutil/trace.go:171","msg":"trace[990149428] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"503.873057ms","start":"2026-04-07T15:11:26.875007Z","end":"2026-04-07T15:11:27.378881Z","steps":["trace[990149428] 'process raft request' (duration: 503.366336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:27.379024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:26.874961Z","time spent":"503.969659ms","remote":"127.0.0.1:34734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:11:28.198938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.182911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:11:28.199007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143681663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-04-07T15:11:28.199066Z","caller":"traceutil/trace.go:171","msg":"trace[1097374829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:250; }","duration":"201.390595ms","start":"2026-04-07T15:11:27.997647Z","end":"2026-04-07T15:11:28.199037Z","steps":["trace[1097374829] 'range keys from in-memory index tree' (duration: 201.053428ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:28.199114Z","caller":"traceutil/trace.go:171","msg":"trace[971370422] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:250; }","duration":"1.143841386s","start":"2026-04-07T15:11:27.055243Z","end":"2026-04-07T15:11:28.199084Z","steps":["trace[971370422] 'agreement among raft nodes before linearized reading' (duration: 323.606445ms)","trace[971370422] 'range keys from in-memory index tree' (duration: 820.046746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:11:28.199177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:27.055189Z","time spent":"1.14397723s","remote":"127.0.0.1:34720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3513,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-07T15:11:28.649375Z","caller":"traceutil/trace.go:171","msg":"trace[1528215136] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"403.856975ms","start":"2026-04-07T15:11:28.245479Z","end":"2026-04-07T15:11:28.649336Z","steps":["trace[1528215136] 'process raft request' (duration: 403.767063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:28.649578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:28.24546Z","time spent":"404.028659ms","remote":"127.0.0.1:34634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:11:28.649725Z","caller":"traceutil/trace.go:171","msg":"trace[53063714] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"441.799976ms","start":"2026-04-07T15:11:28.207894Z","end":"2026-04-07T15:11:28.649694Z","steps":["trace[53063714] 'process raft request' (duration: 439.904583ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:28.64981Z","caller":"traceutil/trace.go:171","msg":"trace[1691093414] linearizableReadLoop","detail":"{readStateIndex:258; appliedIndex:257; }","duration":"407.804243ms","start":"2026-04-07T15:11:28.241993Z","end":"2026-04-07T15:11:28.649798Z","steps":["trace[1691093414] 'read index received' (duration: 405.828249ms)","trace[1691093414] 'applied index is now lower than readState.Index' (duration: 1.974684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:11:28.649987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.982828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T15:11:28.650031Z","caller":"traceutil/trace.go:171","msg":"trace[1298893178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:253; }","duration":"408.068209ms","start":"2026-04-07T15:11:28.241953Z","end":"2026-04-07T15:11:28.650021Z","steps":["trace[1298893178] 'agreement among raft nodes before linearized reading' (duration: 407.973857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:28.650061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:28.241939Z","time spent":"408.114821ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T15:11:28.65014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:28.207881Z","time spent":"441.931949ms","remote":"127.0.0.1:34720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3694,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:11:28.650176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.537508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:11:28.650313Z","caller":"traceutil/trace.go:171","msg":"trace[1319607565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:253; }","duration":"407.738052ms","start":"2026-04-07T15:11:28.242548Z","end":"2026-04-07T15:11:28.650286Z","steps":["trace[1319607565] 'agreement among raft nodes before linearized reading' (duration: 407.523257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:28.65039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:28.242528Z","time spent":"407.849895ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T15:11:54.678039Z","caller":"traceutil/trace.go:171","msg":"trace[304566557] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"637.8008ms","start":"2026-04-07T15:11:54.040219Z","end":"2026-04-07T15:11:54.67802Z","steps":["trace[304566557] 'process raft request' (duration: 637.664957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:54.6782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:54.040199Z","time spent":"637.922813ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:11:54.686025Z","caller":"traceutil/trace.go:171","msg":"trace[1970096874] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"645.583315ms","start":"2026-04-07T15:11:54.040356Z","end":"2026-04-07T15:11:54.68594Z","steps":["trace[1970096874] 'process raft request' (duration: 645.164415ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:54.686122Z","caller":"traceutil/trace.go:171","msg":"trace[1883256998] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"627.221592ms","start":"2026-04-07T15:11:54.058893Z","end":"2026-04-07T15:11:54.686115Z","steps":["trace[1883256998] 'process raft request' (duration: 626.843224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:54.686201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:54.058873Z","time spent":"627.271064ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:11:54.686242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:54.040332Z","time spent":"645.81818ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:11:54.686074Z","caller":"traceutil/trace.go:171","msg":"trace[175256090] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"627.054579ms","start":"2026-04-07T15:11:54.058996Z","end":"2026-04-07T15:11:54.68605Z","steps":["trace[175256090] 'process raft request' (duration: 626.809864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:54.686392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:54.058991Z","time spent":"627.379786ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:11:54.686457Z","caller":"traceutil/trace.go:171","msg":"trace[1437930789] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"601.227791ms","start":"2026-04-07T15:11:54.085212Z","end":"2026-04-07T15:11:54.68644Z","steps":["trace[1437930789] 'process raft request' (duration: 600.811161ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:54.686504Z","caller":"traceutil/trace.go:171","msg":"trace[1440326893] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"600.863252ms","start":"2026-04-07T15:11:54.085628Z","end":"2026-04-07T15:11:54.686491Z","steps":["trace[1440326893] 'process raft request' (duration: 600.547235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:54.686514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:54.085194Z","time spent":"601.291462ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:11:54.686572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:54.085621Z","time spent":"600.905124ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:11:54.686585Z","caller":"traceutil/trace.go:171","msg":"trace[1587167514] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"597.059267ms","start":"2026-04-07T15:11:54.089519Z","end":"2026-04-07T15:11:54.686578Z","steps":["trace[1587167514] 'process raft request' (duration: 596.814711ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:54.686577Z","caller":"traceutil/trace.go:171","msg":"trace[461621473] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"600.826412ms","start":"2026-04-07T15:11:54.085713Z","end":"2026-04-07T15:11:54.68654Z","steps":["trace[461621473] 'process raft request' (duration: 600.535985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:54.686615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:54.089498Z","time spent":"597.102117ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:11:54.686583Z","caller":"traceutil/trace.go:171","msg":"trace[999838527] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"625.74618ms","start":"2026-04-07T15:11:54.060775Z","end":"2026-04-07T15:11:54.686522Z","steps":["trace[999838527] 'process raft request' (duration: 625.098745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:54.686651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:54.085708Z","time spent":"600.909044ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:11:54.686763Z","caller":"traceutil/trace.go:171","msg":"trace[1552535707] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"572.346522ms","start":"2026-04-07T15:11:54.114401Z","end":"2026-04-07T15:11:54.686748Z","steps":["trace[1552535707] 'process raft request' (duration: 572.022744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:54.686852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:54.114375Z","time spent":"572.430874ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:11:54.68686Z","caller":"traceutil/trace.go:171","msg":"trace[720999466] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"601.231771ms","start":"2026-04-07T15:11:54.085263Z","end":"2026-04-07T15:11:54.686494Z","steps":["trace[720999466] 'process raft request' (duration: 600.823392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:54.686851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:54.060768Z","time spent":"625.928993ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:11:54.686914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:54.085259Z","time spent":"601.63038ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:11:54.688019Z","caller":"traceutil/trace.go:171","msg":"trace[1970618841] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"481.356061ms","start":"2026-04-07T15:11:54.206651Z","end":"2026-04-07T15:11:54.688007Z","steps":["trace[1970618841] 'process raft request' (duration: 481.178037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:54.688107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:54.206618Z","time spent":"481.432683ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:12:00.852671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.11029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:12:00.852818Z","caller":"traceutil/trace.go:171","msg":"trace[779706439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"160.362106ms","start":"2026-04-07T15:12:00.692427Z","end":"2026-04-07T15:12:00.852789Z","steps":["trace[779706439] 'range keys from in-memory index tree' (duration: 159.896825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:01.376094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710899779970977310,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T15:12:01.6256Z","caller":"traceutil/trace.go:171","msg":"trace[362688853] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"757.586855ms","start":"2026-04-07T15:12:00.867983Z","end":"2026-04-07T15:12:01.625569Z","steps":["trace[362688853] 'process raft request' (duration: 757.34913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:01.625771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:00.867953Z","time spent":"757.728798ms","remote":"127.0.0.1:34734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:12:02.023265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.719798ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:12:02.02345Z","caller":"traceutil/trace.go:171","msg":"trace[1502483926] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:626; }","duration":"294.942393ms","start":"2026-04-07T15:12:01.728479Z","end":"2026-04-07T15:12:02.023422Z","steps":["trace[1502483926] 'range keys from in-memory index tree' (duration: 294.686727ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:02.023617Z","caller":"traceutil/trace.go:171","msg":"trace[424622681] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.021980594s","start":"2026-04-07T15:12:01.001602Z","end":"2026-04-07T15:12:02.023583Z","steps":["trace[424622681] 'process raft request' (duration: 1.017417401s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:02.023845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:01.001552Z","time spent":"1.022160418s","remote":"127.0.0.1:34656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:12:02.097371Z","caller":"traceutil/trace.go:171","msg":"trace[520925545] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:647; }","duration":"1.222208053s","start":"2026-04-07T15:12:00.875125Z","end":"2026-04-07T15:12:02.097333Z","steps":["trace[520925545] 'read index received' (duration: 750.285922ms)","trace[520925545] 'applied index is now lower than readState.Index' (duration: 471.919791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:12:02.097703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22254948s","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-04-07T15:12:02.097766Z","caller":"traceutil/trace.go:171","msg":"trace[1605936662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"1.222695614s","start":"2026-04-07T15:12:00.875055Z","end":"2026-04-07T15:12:02.097751Z","steps":["trace[1605936662] 'agreement among raft nodes before linearized reading' (duration: 1.222454588s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:02.097821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:00.875028Z","time spent":"1.222780116s","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T15:12:02.597945Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710899779970977315,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T15:12:03.053306Z","caller":"traceutil/trace.go:171","msg":"trace[1042375904] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"961.663782ms","start":"2026-04-07T15:12:02.091609Z","end":"2026-04-07T15:12:03.053272Z","steps":["trace[1042375904] 'process raft request' (duration: 961.396026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:03.053636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:02.091568Z","time spent":"961.846335ms","remote":"127.0.0.1:34790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:12:03.083531Z","caller":"traceutil/trace.go:171","msg":"trace[101209998] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"986.042589ms","start":"2026-04-07T15:12:02.097462Z","end":"2026-04-07T15:12:03.083505Z","steps":["trace[101209998] 'read index received' (duration: 955.466223ms)","trace[101209998] 'applied index is now lower than readState.Index' (duration: 30.575326ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:12:03.083683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392203945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:12:03.083801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.207007642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T15:12:03.08379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.201257853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T15:12:03.083828Z","caller":"traceutil/trace.go:171","msg":"trace[1318495885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"1.392399059s","start":"2026-04-07T15:12:01.691409Z","end":"2026-04-07T15:12:03.083808Z","steps":["trace[1318495885] 'agreement among raft nodes before linearized reading' (duration: 1.392173054s)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:03.083842Z","caller":"traceutil/trace.go:171","msg":"trace[1746674164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"2.207083785s","start":"2026-04-07T15:12:00.876746Z","end":"2026-04-07T15:12:03.08383Z","steps":["trace[1746674164] 'agreement among raft nodes before linearized reading' (duration: 2.20689995s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:03.08388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:00.876739Z","time spent":"2.207134046s","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T15:12:03.08388Z","caller":"traceutil/trace.go:171","msg":"trace[691853886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"2.201374297s","start":"2026-04-07T15:12:00.882474Z","end":"2026-04-07T15:12:03.083848Z","steps":["trace[691853886] 'agreement among raft nodes before linearized reading' (duration: 2.201149261s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:03.0839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:01.691385Z","time spent":"1.392501952s","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:12:03.083936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:00.882457Z","time spent":"2.201470159s","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T15:12:03.083939Z","caller":"traceutil/trace.go:171","msg":"trace[278022997] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"982.253064ms","start":"2026-04-07T15:12:02.10166Z","end":"2026-04-07T15:12:03.083913Z","steps":["trace[278022997] 'process raft request' (duration: 981.635409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:03.08409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:02.101631Z","time spent":"982.369526ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:12:03.084427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.208967468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T15:12:03.084551Z","caller":"traceutil/trace.go:171","msg":"trace[806090108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"2.20911896s","start":"2026-04-07T15:12:00.875416Z","end":"2026-04-07T15:12:03.084535Z","steps":["trace[806090108] 'agreement among raft nodes before linearized reading' (duration: 2.208945076s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:03.084588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:00.8754Z","time spent":"2.209177942s","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T15:12:03.190822Z","caller":"traceutil/trace.go:171","msg":"trace[508879831] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"101.692321ms","start":"2026-04-07T15:12:03.089108Z","end":"2026-04-07T15:12:03.1908Z","steps":["trace[508879831] 'read index received' (duration: 59.863073ms)","trace[508879831] 'applied index is now lower than readState.Index' (duration: 41.827768ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:12:03.190961Z","caller":"traceutil/trace.go:171","msg":"trace[1936803159] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"102.071939ms","start":"2026-04-07T15:12:03.088873Z","end":"2026-04-07T15:12:03.190945Z","steps":["trace[1936803159] 'process raft request' (duration: 60.085408ms)","trace[1936803159] 'compare' (duration: 41.401378ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:12:03.191179Z","caller":"traceutil/trace.go:171","msg":"trace[263783287] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"102.272433ms","start":"2026-04-07T15:12:03.088901Z","end":"2026-04-07T15:12:03.191174Z","steps":["trace[263783287] 'process raft request' (duration: 101.652629ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:03.191257Z","caller":"traceutil/trace.go:171","msg":"trace[1475967561] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"101.810213ms","start":"2026-04-07T15:12:03.089442Z","end":"2026-04-07T15:12:03.191252Z","steps":["trace[1475967561] 'process raft request' (duration: 101.23193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:03.191386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.283083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-07T15:12:03.191408Z","caller":"traceutil/trace.go:171","msg":"trace[248070406] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:632; }","duration":"102.372196ms","start":"2026-04-07T15:12:03.089031Z","end":"2026-04-07T15:12:03.191403Z","steps":["trace[248070406] 'agreement among raft nodes before linearized reading' (duration: 102.282904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:26.173503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.441449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:12:26.173732Z","caller":"traceutil/trace.go:171","msg":"trace[532187560] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:837; }","duration":"479.770238ms","start":"2026-04-07T15:12:25.693938Z","end":"2026-04-07T15:12:26.173708Z","steps":["trace[532187560] 'read index received' (duration: 358.128944ms)","trace[532187560] 'applied index is now lower than readState.Index' (duration: 121.640384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:12:26.173809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:12:26.173838Z","caller":"traceutil/trace.go:171","msg":"trace[1164913736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"479.953661ms","start":"2026-04-07T15:12:25.693876Z","end":"2026-04-07T15:12:26.173829Z","steps":["trace[1164913736] 'agreement among raft nodes before linearized reading' (duration: 479.88964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:26.173867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:25.693852Z","time spent":"480.008933ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T15:12:26.174064Z","caller":"traceutil/trace.go:171","msg":"trace[324830928] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"870.426797ms","start":"2026-04-07T15:12:25.303602Z","end":"2026-04-07T15:12:26.174029Z","steps":["trace[324830928] 'process raft request' (duration: 870.017268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:26.17431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:25.303589Z","time spent":"870.59658ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:12:26.174524Z","caller":"traceutil/trace.go:171","msg":"trace[368560733] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"872.328219ms","start":"2026-04-07T15:12:25.301709Z","end":"2026-04-07T15:12:26.174038Z","steps":["trace[368560733] 'process raft request' (duration: 750.265516ms)","trace[368560733] 'compare' (duration: 121.139092ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:12:26.17479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:25.301693Z","time spent":"872.909431ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:12:26.204877Z","caller":"traceutil/trace.go:171","msg":"trace[1206376152] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"392.009829ms","start":"2026-04-07T15:12:25.812842Z","end":"2026-04-07T15:12:26.204852Z","steps":["trace[1206376152] 'process raft request' (duration: 391.807214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:26.205094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:25.812809Z","time spent":"392.182543ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:12:26.20524Z","caller":"traceutil/trace.go:171","msg":"trace[1785152129] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"276.666979ms","start":"2026-04-07T15:12:25.928559Z","end":"2026-04-07T15:12:26.205226Z","steps":["trace[1785152129] 'process raft request' (duration: 276.206269ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:55.241095Z","caller":"traceutil/trace.go:171","msg":"trace[155676187] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"196.858169ms","start":"2026-04-07T15:12:55.044215Z","end":"2026-04-07T15:12:55.241074Z","steps":["trace[155676187] 'process raft request' (duration: 196.692915ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:01.475802Z","caller":"traceutil/trace.go:171","msg":"trace[1432937081] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"621.30243ms","start":"2026-04-07T15:13:00.854455Z","end":"2026-04-07T15:13:01.475757Z","steps":["trace[1432937081] 'process raft request' (duration: 621.006212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:01.476104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:00.854436Z","time spent":"621.511434ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:13:02.428672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.1208ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:13:02.428781Z","caller":"traceutil/trace.go:171","msg":"trace[1138604137] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1028; }","duration":"701.252243ms","start":"2026-04-07T15:13:01.727514Z","end":"2026-04-07T15:13:02.428767Z","steps":["trace[1138604137] 'range keys from in-memory index tree' (duration: 701.100529ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:02.432242Z","caller":"traceutil/trace.go:171","msg":"trace[857141583] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"1.258578399s","start":"2026-04-07T15:13:01.173642Z","end":"2026-04-07T15:13:02.43222Z","steps":["trace[857141583] 'process raft request' (duration: 1.258422105s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:02.432374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:01.17362Z","time spent":"1.25868049s","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:13:02.432446Z","caller":"traceutil/trace.go:171","msg":"trace[876229989] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"1.172278752s","start":"2026-04-07T15:13:01.260141Z","end":"2026-04-07T15:13:02.432419Z","steps":["trace[876229989] 'process raft request' (duration: 1.172047797s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:02.432581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:01.260116Z","time spent":"1.172399635s","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:13:02.436543Z","caller":"traceutil/trace.go:171","msg":"trace[613145858] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1065; }","duration":"1.041731679s","start":"2026-04-07T15:13:01.394793Z","end":"2026-04-07T15:13:02.436525Z","steps":["trace[613145858] 'read index received' (duration: 80.528555ms)","trace[613145858] 'applied index is now lower than readState.Index' (duration: 961.201824ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:13:02.436753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.943908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T15:13:02.436783Z","caller":"traceutil/trace.go:171","msg":"trace[1281209248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1030; }","duration":"953.02896ms","start":"2026-04-07T15:13:01.483746Z","end":"2026-04-07T15:13:02.436775Z","steps":["trace[1281209248] 'agreement among raft nodes before linearized reading' (duration: 952.912877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:02.436809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:01.483722Z","time spent":"953.081161ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T15:13:02.436801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041990735s","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-04-07T15:13:02.436894Z","caller":"traceutil/trace.go:171","msg":"trace[1728518494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1030; }","duration":"1.042120408s","start":"2026-04-07T15:13:01.394751Z","end":"2026-04-07T15:13:02.436871Z","steps":["trace[1728518494] 'agreement among raft nodes before linearized reading' (duration: 1.041880702s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:02.436949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:01.394721Z","time spent":"1.04221833s","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T15:13:23.909572Z","caller":"traceutil/trace.go:171","msg":"trace[1271716133] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1143; }","duration":"216.668746ms","start":"2026-04-07T15:13:23.692878Z","end":"2026-04-07T15:13:23.909547Z","steps":["trace[1271716133] 'read index received' (duration: 216.419441ms)","trace[1271716133] 'applied index is now lower than readState.Index' (duration: 248.165µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:13:23.909705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.801719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:13:23.909754Z","caller":"traceutil/trace.go:171","msg":"trace[1377308994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"216.892141ms","start":"2026-04-07T15:13:23.692846Z","end":"2026-04-07T15:13:23.909738Z","steps":["trace[1377308994] 'agreement among raft nodes before linearized reading' (duration: 216.802199ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:23.909974Z","caller":"traceutil/trace.go:171","msg":"trace[1827699668] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"277.377195ms","start":"2026-04-07T15:13:23.632564Z","end":"2026-04-07T15:13:23.909942Z","steps":["trace[1827699668] 'process raft request' (duration: 276.745971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:24.879103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.470857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:13:24.879282Z","caller":"traceutil/trace.go:171","msg":"trace[1680804831] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"283.431541ms","start":"2026-04-07T15:13:24.595776Z","end":"2026-04-07T15:13:24.879208Z","steps":["trace[1680804831] 'process raft request' (duration: 44.75075ms)","trace[1680804831] 'compare' (duration: 238.343794ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:13:24.961731Z","caller":"traceutil/trace.go:171","msg":"trace[739725405] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1146; }","duration":"268.403542ms","start":"2026-04-07T15:13:24.693305Z","end":"2026-04-07T15:13:24.961708Z","steps":["trace[739725405] 'read index received' (duration: 268.256969ms)","trace[739725405] 'applied index is now lower than readState.Index' (duration: 140.953µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:13:24.96178Z","caller":"traceutil/trace.go:171","msg":"trace[1533157787] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"365.43649ms","start":"2026-04-07T15:13:24.596305Z","end":"2026-04-07T15:13:24.961742Z","steps":["trace[1533157787] 'process raft request' (duration: 365.316157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:24.96185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.523215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:13:24.961885Z","caller":"traceutil/trace.go:171","msg":"trace[1384213000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1106; }","duration":"268.614597ms","start":"2026-04-07T15:13:24.69326Z","end":"2026-04-07T15:13:24.961875Z","steps":["trace[1384213000] 'agreement among raft nodes before linearized reading' (duration: 268.540765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:24.961937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:24.596291Z","time spent":"365.558812ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:13:24.961955Z","caller":"traceutil/trace.go:171","msg":"trace[60304885] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"365.271206ms","start":"2026-04-07T15:13:24.596657Z","end":"2026-04-07T15:13:24.961928Z","steps":["trace[60304885] 'process raft request' (duration: 365.0026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:24.962091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:24.596642Z","time spent":"365.388538ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:13:24.962198Z","caller":"traceutil/trace.go:171","msg":"trace[817401247] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"366.367971ms","start":"2026-04-07T15:13:24.59581Z","end":"2026-04-07T15:13:24.962178Z","steps":["trace[817401247] 'process raft request' (duration: 365.693316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:24.962297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:24.595798Z","time spent":"366.461892ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:13:27.243429Z","caller":"traceutil/trace.go:171","msg":"trace[1869641837] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"261.77391ms","start":"2026-04-07T15:13:26.981624Z","end":"2026-04-07T15:13:27.243398Z","steps":["trace[1869641837] 'process raft request' (duration: 261.578655ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:27.262224Z","caller":"traceutil/trace.go:171","msg":"trace[755550510] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"280.538542ms","start":"2026-04-07T15:13:26.981654Z","end":"2026-04-07T15:13:27.262193Z","steps":["trace[755550510] 'process raft request' (duration: 280.307667ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:27.262692Z","caller":"traceutil/trace.go:171","msg":"trace[1454995852] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"281.011893ms","start":"2026-04-07T15:13:26.981653Z","end":"2026-04-07T15:13:27.262665Z","steps":["trace[1454995852] 'process raft request' (duration: 280.46135ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:39.49388Z","caller":"traceutil/trace.go:171","msg":"trace[1915046645] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"154.408839ms","start":"2026-04-07T15:13:39.339444Z","end":"2026-04-07T15:13:39.493853Z","steps":["trace[1915046645] 'process raft request' (duration: 154.260466ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:39.494399Z","caller":"traceutil/trace.go:171","msg":"trace[1462604626] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"154.497201ms","start":"2026-04-07T15:13:39.339872Z","end":"2026-04-07T15:13:39.494369Z","steps":["trace[1462604626] 'process raft request' (duration: 154.366958ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:41.472703Z","caller":"traceutil/trace.go:171","msg":"trace[112809114] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1260; }","duration":"133.256522ms","start":"2026-04-07T15:13:41.339431Z","end":"2026-04-07T15:13:41.472688Z","steps":["trace[112809114] 'read index received' (duration: 133.098629ms)","trace[112809114] 'applied index is now lower than readState.Index' (duration: 157.293µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:13:41.472751Z","caller":"traceutil/trace.go:171","msg":"trace[1483999416] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"492.640201ms","start":"2026-04-07T15:13:40.980092Z","end":"2026-04-07T15:13:41.472732Z","steps":["trace[1483999416] 'process raft request' (duration: 492.486938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:41.472827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.392785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T15:13:41.472847Z","caller":"traceutil/trace.go:171","msg":"trace[1955309277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1213; }","duration":"133.443767ms","start":"2026-04-07T15:13:41.339397Z","end":"2026-04-07T15:13:41.472841Z","steps":["trace[1955309277] 'agreement among raft nodes before linearized reading' (duration: 133.351775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:41.472865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:40.980076Z","time spent":"492.720763ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:13:42.124155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.045015ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:13:42.124251Z","caller":"traceutil/trace.go:171","msg":"trace[630360089] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1213; }","duration":"396.150717ms","start":"2026-04-07T15:13:41.728085Z","end":"2026-04-07T15:13:42.124236Z","steps":["trace[630360089] 'range keys from in-memory index tree' (duration: 396.032815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:42.126288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.158386ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T15:13:42.126534Z","caller":"traceutil/trace.go:171","msg":"trace[1012389239] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"625.946765ms","start":"2026-04-07T15:13:41.500544Z","end":"2026-04-07T15:13:42.126491Z","steps":["trace[1012389239] 'read index received' (duration: 383.688756ms)","trace[1012389239] 'applied index is now lower than readState.Index' (duration: 242.254459ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:13:42.126645Z","caller":"traceutil/trace.go:171","msg":"trace[641392353] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"624.37677ms","start":"2026-04-07T15:13:41.50222Z","end":"2026-04-07T15:13:42.126597Z","steps":["trace[641392353] 'process raft request' (duration: 624.190055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:42.126781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:41.50215Z","time spent":"624.539693ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:13:42.126872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.306493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T15:13:42.126918Z","caller":"traceutil/trace.go:171","msg":"trace[1321810762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1214; }","duration":"626.408955ms","start":"2026-04-07T15:13:41.500496Z","end":"2026-04-07T15:13:42.126905Z","steps":["trace[1321810762] 'agreement among raft nodes before linearized reading' (duration: 626.193901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:42.126972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:41.500472Z","time spent":"626.479727ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T15:13:42.127174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.413445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T15:13:42.127257Z","caller":"traceutil/trace.go:171","msg":"trace[559568877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1214; }","duration":"626.516648ms","start":"2026-04-07T15:13:41.500728Z","end":"2026-04-07T15:13:42.127244Z","steps":["trace[559568877] 'agreement among raft nodes before linearized reading' (duration: 626.365405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:42.127293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:41.500718Z","time spent":"626.564329ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T15:13:42.127356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.097651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T15:13:42.127361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.426929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:13:42.127398Z","caller":"traceutil/trace.go:171","msg":"trace[1948139696] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1214; }","duration":"394.169452ms","start":"2026-04-07T15:13:41.733217Z","end":"2026-04-07T15:13:42.127387Z","steps":["trace[1948139696] 'agreement among raft nodes before linearized reading' (duration: 394.0641ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:42.127412Z","caller":"traceutil/trace.go:171","msg":"trace[1453944881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"434.520051ms","start":"2026-04-07T15:13:41.692882Z","end":"2026-04-07T15:13:42.127402Z","steps":["trace[1453944881] 'agreement among raft nodes before linearized reading' (duration: 434.4534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:42.127426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:41.733201Z","time spent":"394.218913ms","remote":"127.0.0.1:35070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:13:42.12744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:41.692863Z","time spent":"434.570922ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:13:42.127441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.776842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:13:42.127471Z","caller":"traceutil/trace.go:171","msg":"trace[1999060734] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1214; }","duration":"578.808233ms","start":"2026-04-07T15:13:41.548654Z","end":"2026-04-07T15:13:42.127462Z","steps":["trace[1999060734] 'agreement among raft nodes before linearized reading' (duration: 578.755362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:42.1275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:41.548579Z","time spent":"578.914525ms","remote":"127.0.0.1:34946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:13:42.127637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.031487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T15:13:42.127659Z","caller":"traceutil/trace.go:171","msg":"trace[1468065353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1214; }","duration":"125.089319ms","start":"2026-04-07T15:13:42.002562Z","end":"2026-04-07T15:13:42.127652Z","steps":["trace[1468065353] 'agreement among raft nodes before linearized reading' (duration: 124.997877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:42.127724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.439971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:13:42.127741Z","caller":"traceutil/trace.go:171","msg":"trace[1121226647] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1214; }","duration":"344.458182ms","start":"2026-04-07T15:13:41.783277Z","end":"2026-04-07T15:13:42.127735Z","steps":["trace[1121226647] 'agreement among raft nodes before linearized reading' (duration: 344.429791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:42.127759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:41.783181Z","time spent":"344.572234ms","remote":"127.0.0.1:57922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:13:57.047972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.212992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:13:57.048097Z","caller":"traceutil/trace.go:171","msg":"trace[1355483495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"338.397025ms","start":"2026-04-07T15:13:56.709671Z","end":"2026-04-07T15:13:57.048068Z","steps":["trace[1355483495] 'range keys from in-memory index tree' (duration: 338.111779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:57.048278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:56.709654Z","time spent":"338.55713ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:13:57.049176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.929829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.90\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-07T15:13:57.049287Z","caller":"traceutil/trace.go:171","msg":"trace[1715489272] range","detail":"{range_begin:/registry/masterleases/199.19.213.90; range_end:; response_count:1; response_revision:1272; }","duration":"770.110574ms","start":"2026-04-07T15:13:56.279147Z","end":"2026-04-07T15:13:57.049258Z","steps":["trace[1715489272] 'range keys from in-memory index tree' (duration: 769.54957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:57.049343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:56.279126Z","time spent":"770.207325ms","remote":"127.0.0.1:34580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.90\" "} {"level":"info","ts":"2026-04-07T15:13:57.074101Z","caller":"traceutil/trace.go:171","msg":"trace[1937011728] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"233.630574ms","start":"2026-04-07T15:13:56.84044Z","end":"2026-04-07T15:13:57.074071Z","steps":["trace[1937011728] 'process raft request' (duration: 233.4644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:57.464142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:57.05065Z","time spent":"413.487438ms","remote":"127.0.0.1:34580","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T15:13:58.463525Z","caller":"traceutil/trace.go:171","msg":"trace[749104635] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"228.338275ms","start":"2026-04-07T15:13:58.235158Z","end":"2026-04-07T15:13:58.463497Z","steps":["trace[749104635] 'process raft request' (duration: 228.160321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:59.155989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.006085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:13:59.15618Z","caller":"traceutil/trace.go:171","msg":"trace[451210003] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1327; }","duration":"914.416445ms","start":"2026-04-07T15:13:58.241747Z","end":"2026-04-07T15:13:59.156164Z","steps":["trace[451210003] 'read index received' (duration: 221.536631ms)","trace[451210003] 'applied index is now lower than readState.Index' (duration: 692.878504ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:13:59.156256Z","caller":"traceutil/trace.go:171","msg":"trace[1275934312] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"796.890297ms","start":"2026-04-07T15:13:58.359356Z","end":"2026-04-07T15:13:59.156246Z","steps":["trace[1275934312] 'process raft request' (duration: 796.725393ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:59.156239Z","caller":"traceutil/trace.go:171","msg":"trace[271050535] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"920.679437ms","start":"2026-04-07T15:13:58.235522Z","end":"2026-04-07T15:13:59.156201Z","steps":["trace[271050535] 'process raft request' (duration: 618.386965ms)","trace[271050535] 'compare' (duration: 301.652637ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:13:59.156318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:58.359316Z","time spent":"796.974829ms","remote":"127.0.0.1:34634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:13:59.15639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:58.235502Z","time spent":"920.805989ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:13:59.156659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.913237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T15:13:59.156699Z","caller":"traceutil/trace.go:171","msg":"trace[1772263844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1278; }","duration":"914.996109ms","start":"2026-04-07T15:13:58.241693Z","end":"2026-04-07T15:13:59.156689Z","steps":["trace[1772263844] 'agreement among raft nodes before linearized reading' (duration: 914.805025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:59.156737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:58.241669Z","time spent":"915.06178ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T15:13:59.314362Z","caller":"traceutil/trace.go:171","msg":"trace[268545227] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"629.935445ms","start":"2026-04-07T15:13:58.684389Z","end":"2026-04-07T15:13:59.314325Z","steps":["trace[268545227] 'process raft request' (duration: 585.82911ms)","trace[268545227] 'compare' (duration: 43.935551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:13:59.314568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:58.684335Z","time spent":"630.122669ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:13:59.395634Z","caller":"traceutil/trace.go:171","msg":"trace[168506410] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1330; }","duration":"239.397064ms","start":"2026-04-07T15:13:59.156216Z","end":"2026-04-07T15:13:59.395613Z","steps":["trace[168506410] 'read index received' (duration: 114.113021ms)","trace[168506410] 'applied index is now lower than readState.Index' (duration: 125.283053ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:13:59.395898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149022311s","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-04-07T15:13:59.395974Z","caller":"traceutil/trace.go:171","msg":"trace[1576310010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1282; }","duration":"1.149147124s","start":"2026-04-07T15:13:58.246816Z","end":"2026-04-07T15:13:59.395963Z","steps":["trace[1576310010] 'agreement among raft nodes before linearized reading' (duration: 1.148891828s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:59.396042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:58.246803Z","time spent":"1.149226386s","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T15:13:59.396471Z","caller":"traceutil/trace.go:171","msg":"trace[681175563] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"316.620945ms","start":"2026-04-07T15:13:59.079838Z","end":"2026-04-07T15:13:59.396458Z","steps":["trace[681175563] 'process raft request' (duration: 315.561042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:59.39657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:59.079805Z","time spent":"316.703666ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:13:59.39692Z","caller":"traceutil/trace.go:171","msg":"trace[248666354] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"236.197513ms","start":"2026-04-07T15:13:59.160711Z","end":"2026-04-07T15:13:59.396908Z","steps":["trace[248666354] 'process raft request' (duration: 234.814031ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:59.397134Z","caller":"traceutil/trace.go:171","msg":"trace[1660553272] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"234.639648ms","start":"2026-04-07T15:13:59.162483Z","end":"2026-04-07T15:13:59.397122Z","steps":["trace[1660553272] 'process raft request' (duration: 233.087412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:59.397531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.964359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-04-07T15:13:59.397571Z","caller":"traceutil/trace.go:171","msg":"trace[1522589200] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1282; }","duration":"556.03844ms","start":"2026-04-07T15:13:58.841522Z","end":"2026-04-07T15:13:59.397561Z","steps":["trace[1522589200] 'agreement among raft nodes before linearized reading' (duration: 555.897907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:59.397602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:58.841489Z","time spent":"556.106111ms","remote":"127.0.0.1:34720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5352,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-07T15:13:59.397778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.544285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:13:59.397836Z","caller":"traceutil/trace.go:171","msg":"trace[1989430525] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1282; }","duration":"597.646736ms","start":"2026-04-07T15:13:58.800176Z","end":"2026-04-07T15:13:59.397823Z","steps":["trace[1989430525] 'agreement among raft nodes before linearized reading' (duration: 597.525104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:59.397865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:58.800162Z","time spent":"597.698118ms","remote":"127.0.0.1:59128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:13:59.398013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.252647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:13:59.398039Z","caller":"traceutil/trace.go:171","msg":"trace[1824100388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"707.304259ms","start":"2026-04-07T15:13:58.690729Z","end":"2026-04-07T15:13:59.398034Z","steps":["trace[1824100388] 'agreement among raft nodes before linearized reading' (duration: 707.263188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:59.398051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:58.690712Z","time spent":"707.336089ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T15:13:59.557501Z","caller":"traceutil/trace.go:171","msg":"trace[266053412] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"155.646468ms","start":"2026-04-07T15:13:59.401824Z","end":"2026-04-07T15:13:59.55747Z","steps":["trace[266053412] 'process raft request' (duration: 84.786961ms)","trace[266053412] 'compare' (duration: 70.703784ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:13:59.578707Z","caller":"traceutil/trace.go:171","msg":"trace[867333687] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"171.066875ms","start":"2026-04-07T15:13:59.407617Z","end":"2026-04-07T15:13:59.578684Z","steps":["trace[867333687] 'process raft request' (duration: 171.003294ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:59.578731Z","caller":"traceutil/trace.go:171","msg":"trace[2012514390] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"176.746643ms","start":"2026-04-07T15:13:59.401958Z","end":"2026-04-07T15:13:59.578704Z","steps":["trace[2012514390] 'process raft request' (duration: 176.496107ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:00.600791Z","caller":"traceutil/trace.go:171","msg":"trace[2102864117] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1340; }","duration":"335.989701ms","start":"2026-04-07T15:14:00.264776Z","end":"2026-04-07T15:14:00.600765Z","steps":["trace[2102864117] 'read index received' (duration: 335.857508ms)","trace[2102864117] 'applied index is now lower than readState.Index' (duration: 131.283µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:14:00.601059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.009907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T15:14:00.601097Z","caller":"traceutil/trace.go:171","msg":"trace[114761074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1289; }","duration":"133.096839ms","start":"2026-04-07T15:14:00.467991Z","end":"2026-04-07T15:14:00.601088Z","steps":["trace[114761074] 'agreement among raft nodes before linearized reading' (duration: 132.909955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:00.601305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.504533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-g8dgx\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-04-07T15:14:00.601507Z","caller":"traceutil/trace.go:171","msg":"trace[1326664653] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-g8dgx; range_end:; response_count:1; response_revision:1289; }","duration":"336.922823ms","start":"2026-04-07T15:14:00.26457Z","end":"2026-04-07T15:14:00.601493Z","steps":["trace[1326664653] 'agreement among raft nodes before linearized reading' (duration: 336.462882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:00.601677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:00.264554Z","time spent":"337.107397ms","remote":"127.0.0.1:34734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11079,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-g8dgx\" "} {"level":"info","ts":"2026-04-07T15:14:00.601397Z","caller":"traceutil/trace.go:171","msg":"trace[141387491] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"382.426907ms","start":"2026-04-07T15:14:00.218364Z","end":"2026-04-07T15:14:00.600791Z","steps":["trace[141387491] 'process raft request' (duration: 382.285214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:00.602055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:00.218345Z","time spent":"383.630475ms","remote":"127.0.0.1:34634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:14:00.817099Z","caller":"traceutil/trace.go:171","msg":"trace[1867850932] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"123.579794ms","start":"2026-04-07T15:14:00.693487Z","end":"2026-04-07T15:14:00.817067Z","steps":["trace[1867850932] 'read index received' (duration: 123.427171ms)","trace[1867850932] 'applied index is now lower than readState.Index' (duration: 152.123µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:14:00.817178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.674238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:14:00.817195Z","caller":"traceutil/trace.go:171","msg":"trace[982088645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"123.748779ms","start":"2026-04-07T15:14:00.693441Z","end":"2026-04-07T15:14:00.81719Z","steps":["trace[982088645] 'agreement among raft nodes before linearized reading' (duration: 123.700158ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:00.817163Z","caller":"traceutil/trace.go:171","msg":"trace[606146471] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"204.215722ms","start":"2026-04-07T15:14:00.612916Z","end":"2026-04-07T15:14:00.817132Z","steps":["trace[606146471] 'process raft request' (duration: 204.052447ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:00.966151Z","caller":"traceutil/trace.go:171","msg":"trace[751322277] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"140.997628ms","start":"2026-04-07T15:14:00.825131Z","end":"2026-04-07T15:14:00.966128Z","steps":["trace[751322277] 'process raft request' (duration: 129.96821ms)","trace[751322277] 'compare' (duration: 10.839514ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:14:01.022861Z","caller":"traceutil/trace.go:171","msg":"trace[1015414882] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"197.259785ms","start":"2026-04-07T15:14:00.825562Z","end":"2026-04-07T15:14:01.022821Z","steps":["trace[1015414882] 'process raft request' (duration: 196.729593ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:01.022843Z","caller":"traceutil/trace.go:171","msg":"trace[715498602] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"194.484682ms","start":"2026-04-07T15:14:00.828332Z","end":"2026-04-07T15:14:01.022817Z","steps":["trace[715498602] 'process raft request' (duration: 194.272777ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:01.323752Z","caller":"traceutil/trace.go:171","msg":"trace[316112004] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"126.94775ms","start":"2026-04-07T15:14:01.196779Z","end":"2026-04-07T15:14:01.323727Z","steps":["trace[316112004] 'read index received' (duration: 126.737506ms)","trace[316112004] 'applied index is now lower than readState.Index' (duration: 209.334µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:14:01.323914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.115364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:14:01.323952Z","caller":"traceutil/trace.go:171","msg":"trace[746739664] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1295; }","duration":"127.215766ms","start":"2026-04-07T15:14:01.196728Z","end":"2026-04-07T15:14:01.323943Z","steps":["trace[746739664] 'agreement among raft nodes before linearized reading' (duration: 127.108984ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:01.324187Z","caller":"traceutil/trace.go:171","msg":"trace[652678794] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"157.726415ms","start":"2026-04-07T15:14:01.166441Z","end":"2026-04-07T15:14:01.324167Z","steps":["trace[652678794] 'process raft request' (duration: 157.141851ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:11.415022Z","caller":"traceutil/trace.go:171","msg":"trace[315495228] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"107.37007ms","start":"2026-04-07T15:14:11.307635Z","end":"2026-04-07T15:14:11.415005Z","steps":["trace[315495228] 'process raft request' (duration: 107.073314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:11.948923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710899779970978978,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T15:14:12.258538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.25562ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:14:12.259048Z","caller":"traceutil/trace.go:171","msg":"trace[401563390] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1371; }","duration":"530.71901ms","start":"2026-04-07T15:14:11.728251Z","end":"2026-04-07T15:14:12.25897Z","steps":["trace[401563390] 'range keys from in-memory index tree' (duration: 530.239039ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:12.259897Z","caller":"traceutil/trace.go:171","msg":"trace[731672908] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1425; }","duration":"811.998227ms","start":"2026-04-07T15:14:11.447876Z","end":"2026-04-07T15:14:12.259874Z","steps":["trace[731672908] 'read index received' (duration: 738.329707ms)","trace[731672908] 'applied index is now lower than readState.Index' (duration: 73.66732ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:14:12.260023Z","caller":"traceutil/trace.go:171","msg":"trace[389672349] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"840.911069ms","start":"2026-04-07T15:14:11.419084Z","end":"2026-04-07T15:14:12.259995Z","steps":["trace[389672349] 'process raft request' (duration: 767.191098ms)","trace[389672349] 'compare' (duration: 71.800628ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:14:12.260039Z","caller":"traceutil/trace.go:171","msg":"trace[700502098] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"828.176161ms","start":"2026-04-07T15:14:11.431848Z","end":"2026-04-07T15:14:12.260024Z","steps":["trace[700502098] 'process raft request' (duration: 827.927136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:12.26019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:11.41906Z","time spent":"841.017192ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:14:12.260315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.432337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T15:14:12.260364Z","caller":"traceutil/trace.go:171","msg":"trace[359484799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1373; }","duration":"812.53482ms","start":"2026-04-07T15:14:11.447817Z","end":"2026-04-07T15:14:12.260352Z","steps":["trace[359484799] 'agreement among raft nodes before linearized reading' (duration: 812.314275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:12.260404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:11.447804Z","time spent":"812.589881ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T15:14:12.260504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:11.431833Z","time spent":"828.247193ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:14:12.260529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.981798ms","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":"warn","ts":"2026-04-07T15:14:12.260717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.732737ms","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-04-07T15:14:12.260733Z","caller":"traceutil/trace.go:171","msg":"trace[2119735627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1373; }","duration":"627.232544ms","start":"2026-04-07T15:14:11.63349Z","end":"2026-04-07T15:14:12.260722Z","steps":["trace[2119735627] 'agreement among raft nodes before linearized reading' (duration: 626.875965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:12.260807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:11.633476Z","time spent":"627.287846ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T15:14:12.260807Z","caller":"traceutil/trace.go:171","msg":"trace[1029955822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1373; }","duration":"462.83601ms","start":"2026-04-07T15:14:11.797919Z","end":"2026-04-07T15:14:12.260755Z","steps":["trace[1029955822] 'agreement among raft nodes before linearized reading' (duration: 462.610714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:12.260867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:11.797895Z","time spent":"462.960333ms","remote":"127.0.0.1:34802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T15:17:29.250633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.917925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-07T15:17:29.250689Z","caller":"traceutil/trace.go:171","msg":"trace[1506794570] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2306; }","duration":"168.028358ms","start":"2026-04-07T15:17:29.08265Z","end":"2026-04-07T15:17:29.250679Z","steps":["trace[1506794570] 'agreement among raft nodes before linearized reading' (duration: 167.870494ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:17:29.250436Z","caller":"traceutil/trace.go:171","msg":"trace[1990062466] transaction","detail":"{read_only:false; response_revision:2306; number_of_response:1; }","duration":"231.790184ms","start":"2026-04-07T15:17:29.018631Z","end":"2026-04-07T15:17:29.250422Z","steps":["trace[1990062466] 'process raft request' (duration: 231.533027ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:17:29.250385Z","caller":"traceutil/trace.go:171","msg":"trace[1927043155] linearizableReadLoop","detail":"{readStateIndex:2401; appliedIndex:2400; }","duration":"167.659309ms","start":"2026-04-07T15:17:29.082704Z","end":"2026-04-07T15:17:29.250364Z","steps":["trace[1927043155] 'read index received' (duration: 167.325411ms)","trace[1927043155] 'applied index is now lower than readState.Index' (duration: 332.938µs)"],"step_count":2}