{"level":"warn","ts":"2026-07-03T19:19:16.277684Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-03T19:19:16.277768Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-07-03T19:19:16.277835Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-03T19:19:16.277849Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-07-03T19:19:16.27787Z","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-07-03T19:19:16.278769Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-07-03T19:19:16.278868Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-07-03T19:19:16.286213Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.113124ms"} {"level":"info","ts":"2026-07-03T19:19:16.297897Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-07-03T19:19:16.297959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-07-03T19:19:16.297977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-07-03T19:19:16.297985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-03T19:19:16.297992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-07-03T19:19:16.298022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-07-03T19:19:16.306088Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-03T19:19:16.308383Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-03T19:19:16.309701Z","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-07-03T19:19:16.312245Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-03T19:19:16.312404Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-03T19:19:16.312605Z","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-07-03T19:19:16.312771Z","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-07-03T19:19:16.312789Z","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-07-03T19:19:16.317871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-07-03T19:19:16.318067Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-07-03T19:19:16.320761Z","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-07-03T19:19:16.321143Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-03T19:19:16.321194Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-03T19:19:16.32135Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-07-03T19:19:16.321377Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-07-03T19:19:17.198978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-07-03T19:19:17.199058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-03T19:19:17.199083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-07-03T19:19:17.199103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-07-03T19:19:17.199115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-07-03T19:19:17.199135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-07-03T19:19:17.199143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-07-03T19:19:17.200145Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-03T19:19:17.20014Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T19:19:17.200227Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T19:19:17.200248Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T19:19:17.200351Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-03T19:19:17.200398Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-03T19:19:17.202575Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-07-03T19:19:17.202952Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-03T19:19:17.208103Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T19:19:17.208198Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T19:19:17.208248Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T19:19:20.192727Z","caller":"traceutil/trace.go:171","msg":"trace[347274488] transaction","detail":"{read_only:false; response_revision:191; number_of_response:1; }","duration":"161.30093ms","start":"2026-07-03T19:19:20.031399Z","end":"2026-07-03T19:19:20.192699Z","steps":["trace[347274488] 'process raft request' (duration: 77.92064ms)","trace[347274488] 'compare' (duration: 83.259099ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:19:20.448605Z","caller":"traceutil/trace.go:171","msg":"trace[319630056] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"107.316077ms","start":"2026-07-03T19:19:20.341263Z","end":"2026-07-03T19:19:20.44858Z","steps":["trace[319630056] 'process raft request' (duration: 102.2437ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:29.510752Z","caller":"traceutil/trace.go:171","msg":"trace[1473424945] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"749.093081ms","start":"2026-07-03T19:19:28.761636Z","end":"2026-07-03T19:19:29.510729Z","steps":["trace[1473424945] 'process raft request' (duration: 748.954301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:29.511364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:28.761584Z","time spent":"749.245462ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:19:31.670564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.052405662s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:19:31.670745Z","caller":"traceutil/trace.go:171","msg":"trace[74333976] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:362; }","duration":"2.277435874s","start":"2026-07-03T19:19:29.393286Z","end":"2026-07-03T19:19:31.670722Z","steps":["trace[74333976] 'read index received' (duration: 117.320246ms)","trace[74333976] 'applied index is now lower than readState.Index' (duration: 2.160113758s)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:31.670899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.277583275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-03T19:19:31.67092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.009286695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T19:19:31.670947Z","caller":"traceutil/trace.go:171","msg":"trace[1069120329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:352; }","duration":"2.277689686s","start":"2026-07-03T19:19:29.393247Z","end":"2026-07-03T19:19:31.670936Z","steps":["trace[1069120329] 'agreement among raft nodes before linearized reading' (duration: 2.277575595s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:31.670964Z","caller":"traceutil/trace.go:171","msg":"trace[1515773036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:352; }","duration":"2.009362855s","start":"2026-07-03T19:19:29.661589Z","end":"2026-07-03T19:19:31.670952Z","steps":["trace[1515773036] 'agreement among raft nodes before linearized reading' (duration: 2.009259714s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:31.670855Z","caller":"traceutil/trace.go:171","msg":"trace[227265031] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"2.796125842s","start":"2026-07-03T19:19:28.874696Z","end":"2026-07-03T19:19:31.670821Z","steps":["trace[227265031] 'process raft request' (duration: 743.219888ms)","trace[227265031] 'compare' (duration: 2.05229096s)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:31.671022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:29.661571Z","time spent":"2.009438045s","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T19:19:31.67105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360993626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-03T19:19:31.671067Z","caller":"traceutil/trace.go:171","msg":"trace[1275135244] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:352; }","duration":"1.361066637s","start":"2026-07-03T19:19:30.309995Z","end":"2026-07-03T19:19:31.671062Z","steps":["trace[1275135244] 'agreement among raft nodes before linearized reading' (duration: 1.361040286s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:31.671088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:30.309975Z","time spent":"1.361110247s","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-07-03T19:19:31.67125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:28.874676Z","time spent":"2.796404544s","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:19:31.671013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.318084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-03T19:19:31.671348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.581847763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-03T19:19:31.67138Z","caller":"traceutil/trace.go:171","msg":"trace[1524049442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:352; }","duration":"156.722858ms","start":"2026-07-03T19:19:31.51465Z","end":"2026-07-03T19:19:31.671373Z","steps":["trace[1524049442] 'agreement among raft nodes before linearized reading' (duration: 156.345604ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:31.671399Z","caller":"traceutil/trace.go:171","msg":"trace[1055974715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:352; }","duration":"1.581900533s","start":"2026-07-03T19:19:30.089485Z","end":"2026-07-03T19:19:31.671386Z","steps":["trace[1055974715] 'agreement among raft nodes before linearized reading' (duration: 1.581805522s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:31.671455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:30.089427Z","time spent":"1.582015164s","remote":"127.0.0.1:46208","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-07-03T19:19:31.670974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:29.393231Z","time spent":"2.277735957s","remote":"127.0.0.1:46208","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-07-03T19:19:32.378929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.08738ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-03T19:19:32.379059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:31.672818Z","time spent":"706.229158ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-03T19:19:32.404685Z","caller":"traceutil/trace.go:171","msg":"trace[965041153] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"729.618512ms","start":"2026-07-03T19:19:31.675029Z","end":"2026-07-03T19:19:32.404648Z","steps":["trace[965041153] 'process raft request' (duration: 729.27997ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:32.404739Z","caller":"traceutil/trace.go:171","msg":"trace[95905622] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"729.421262ms","start":"2026-07-03T19:19:31.675288Z","end":"2026-07-03T19:19:32.404709Z","steps":["trace[95905622] 'process raft request' (duration: 729.23375ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:32.40479Z","caller":"traceutil/trace.go:171","msg":"trace[448140454] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"728.628856ms","start":"2026-07-03T19:19:31.676129Z","end":"2026-07-03T19:19:32.404757Z","steps":["trace[448140454] 'process raft request' (duration: 728.444195ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:32.404775Z","caller":"traceutil/trace.go:171","msg":"trace[2147238369] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"728.495385ms","start":"2026-07-03T19:19:31.676245Z","end":"2026-07-03T19:19:32.40474Z","steps":["trace[2147238369] 'process raft request' (duration: 728.433514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:32.404852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:31.675012Z","time spent":"729.745594ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:19:32.404858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:31.675277Z","time spent":"729.527092ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:19:32.404948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:31.676237Z","time spent":"728.626776ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:19:32.404948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:31.676119Z","time spent":"728.743507ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:19:33.687636Z","caller":"traceutil/trace.go:171","msg":"trace[1532359715] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"100.373996ms","start":"2026-07-03T19:19:33.587239Z","end":"2026-07-03T19:19:33.687613Z","steps":["trace[1532359715] 'process raft request' (duration: 100.240735ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:35.048251Z","caller":"traceutil/trace.go:171","msg":"trace[395997434] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"198.407041ms","start":"2026-07-03T19:19:34.849812Z","end":"2026-07-03T19:19:35.048219Z","steps":["trace[395997434] 'process raft request' (duration: 198.207203ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:38.641953Z","caller":"traceutil/trace.go:171","msg":"trace[2001723797] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"158.786634ms","start":"2026-07-03T19:19:38.483134Z","end":"2026-07-03T19:19:38.641921Z","steps":["trace[2001723797] 'process raft request' (duration: 158.532863ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:38.641942Z","caller":"traceutil/trace.go:171","msg":"trace[709246820] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"108.220752ms","start":"2026-07-03T19:19:38.533627Z","end":"2026-07-03T19:19:38.641848Z","steps":["trace[709246820] 'read index received' (duration: 107.969531ms)","trace[709246820] 'applied index is now lower than readState.Index' (duration: 250.091µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:38.642152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.491913ms","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-07-03T19:19:38.642466Z","caller":"traceutil/trace.go:171","msg":"trace[1126996577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:374; }","duration":"108.860149ms","start":"2026-07-03T19:19:38.53355Z","end":"2026-07-03T19:19:38.64241Z","steps":["trace[1126996577] 'agreement among raft nodes before linearized reading' (duration: 108.39647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:38.64231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.455161ms","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-07-03T19:19:38.642568Z","caller":"traceutil/trace.go:171","msg":"trace[1772588139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:374; }","duration":"108.766435ms","start":"2026-07-03T19:19:38.533773Z","end":"2026-07-03T19:19:38.64254Z","steps":["trace[1772588139] 'agreement among raft nodes before linearized reading' (duration: 108.433201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:38.642493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.704803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T19:19:38.642811Z","caller":"traceutil/trace.go:171","msg":"trace[522814831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"109.211143ms","start":"2026-07-03T19:19:38.533578Z","end":"2026-07-03T19:19:38.642789Z","steps":["trace[522814831] 'agreement among raft nodes before linearized reading' (duration: 108.454761ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:38.792163Z","caller":"traceutil/trace.go:171","msg":"trace[1819672206] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"144.1875ms","start":"2026-07-03T19:19:38.647945Z","end":"2026-07-03T19:19:38.792133Z","steps":["trace[1819672206] 'process raft request' (duration: 143.913449ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:38.793423Z","caller":"traceutil/trace.go:171","msg":"trace[1538954143] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"144.859389ms","start":"2026-07-03T19:19:38.648535Z","end":"2026-07-03T19:19:38.793394Z","steps":["trace[1538954143] 'process raft request' (duration: 144.674251ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:38.793419Z","caller":"traceutil/trace.go:171","msg":"trace[1513062809] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"144.41172ms","start":"2026-07-03T19:19:38.648981Z","end":"2026-07-03T19:19:38.793393Z","steps":["trace[1513062809] 'process raft request' (duration: 144.357398ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:43.256677Z","caller":"traceutil/trace.go:171","msg":"trace[1343671715] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"446.356964ms","start":"2026-07-03T19:19:42.810298Z","end":"2026-07-03T19:19:43.256655Z","steps":["trace[1343671715] 'process raft request' (duration: 446.221309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:43.256801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:42.810285Z","time spent":"446.453869ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:19:43.266438Z","caller":"traceutil/trace.go:171","msg":"trace[916422020] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"451.994656ms","start":"2026-07-03T19:19:42.814426Z","end":"2026-07-03T19:19:43.266421Z","steps":["trace[916422020] 'process raft request' (duration: 451.968725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:43.266536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:42.814417Z","time spent":"452.06918ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:19:43.266676Z","caller":"traceutil/trace.go:171","msg":"trace[745075657] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"454.018507ms","start":"2026-07-03T19:19:42.812627Z","end":"2026-07-03T19:19:43.266646Z","steps":["trace[745075657] 'process raft request' (duration: 453.704575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:43.266854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:42.812619Z","time spent":"454.162602ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:19:44.802339Z","caller":"traceutil/trace.go:171","msg":"trace[158754568] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"134.534393ms","start":"2026-07-03T19:19:44.667784Z","end":"2026-07-03T19:19:44.802319Z","steps":["trace[158754568] 'process raft request' (duration: 43.488211ms)","trace[158754568] 'compare' (duration: 90.957678ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:19:45.630683Z","caller":"traceutil/trace.go:171","msg":"trace[779745563] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"311.188114ms","start":"2026-07-03T19:19:45.31947Z","end":"2026-07-03T19:19:45.630659Z","steps":["trace[779745563] 'process raft request' (duration: 289.805027ms)","trace[779745563] 'compare' (duration: 21.270652ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:45.63083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:45.319429Z","time spent":"311.319449ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:19:46.128087Z","caller":"traceutil/trace.go:171","msg":"trace[1135881036] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"109.716174ms","start":"2026-07-03T19:19:46.018351Z","end":"2026-07-03T19:19:46.128067Z","steps":["trace[1135881036] 'process raft request' (duration: 109.513506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:46.449645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.730001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:19:46.449825Z","caller":"traceutil/trace.go:171","msg":"trace[52543427] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"245.749384ms","start":"2026-07-03T19:19:46.204059Z","end":"2026-07-03T19:19:46.449808Z","steps":["trace[52543427] 'process raft request' (duration: 245.689242ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:46.449845Z","caller":"traceutil/trace.go:171","msg":"trace[758665891] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"246.050726ms","start":"2026-07-03T19:19:46.203767Z","end":"2026-07-03T19:19:46.449817Z","steps":["trace[758665891] 'process raft request' (duration: 100.074966ms)","trace[758665891] 'compare' (duration: 145.580675ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:46.843889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.109467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:19:46.844126Z","caller":"traceutil/trace.go:171","msg":"trace[316579013] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"387.177981ms","start":"2026-07-03T19:19:46.45693Z","end":"2026-07-03T19:19:46.844108Z","steps":["trace[316579013] 'process raft request' (duration: 387.132389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:46.844221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:46.456922Z","time spent":"387.260475ms","remote":"127.0.0.1:46134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:19:46.844325Z","caller":"traceutil/trace.go:171","msg":"trace[1969845132] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"389.973678ms","start":"2026-07-03T19:19:46.454249Z","end":"2026-07-03T19:19:46.844223Z","steps":["trace[1969845132] 'process raft request' (duration: 204.460795ms)","trace[1969845132] 'compare' (duration: 184.977072ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:46.84457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:46.454233Z","time spent":"390.244957ms","remote":"127.0.0.1:46044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:19:46.845198Z","caller":"traceutil/trace.go:171","msg":"trace[855791734] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"388.45291ms","start":"2026-07-03T19:19:46.45672Z","end":"2026-07-03T19:19:46.845173Z","steps":["trace[855791734] 'process raft request' (duration: 387.272795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:46.845424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:46.456695Z","time spent":"388.662088ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:19:46.886801Z","caller":"traceutil/trace.go:171","msg":"trace[1241663518] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"427.70072ms","start":"2026-07-03T19:19:46.459079Z","end":"2026-07-03T19:19:46.88678Z","steps":["trace[1241663518] 'process raft request' (duration: 427.278554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:46.887205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:46.459072Z","time spent":"428.020642ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:19:47.261709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.299599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:19:47.261897Z","caller":"traceutil/trace.go:171","msg":"trace[1285591650] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"409.67773ms","start":"2026-07-03T19:19:46.852194Z","end":"2026-07-03T19:19:47.261872Z","steps":["trace[1285591650] 'process raft request' (duration: 131.143483ms)","trace[1285591650] 'compare' (duration: 278.178743ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:19:47.261974Z","caller":"traceutil/trace.go:171","msg":"trace[875216037] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"372.0075ms","start":"2026-07-03T19:19:46.889944Z","end":"2026-07-03T19:19:47.261952Z","steps":["trace[875216037] 'process raft request' (duration: 371.863414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:47.26202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:46.852177Z","time spent":"409.773973ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:19:47.262088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:46.889923Z","time spent":"372.117294ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:19:47.262085Z","caller":"traceutil/trace.go:171","msg":"trace[1893089363] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"371.000701ms","start":"2026-07-03T19:19:46.891052Z","end":"2026-07-03T19:19:47.262053Z","steps":["trace[1893089363] 'process raft request' (duration: 370.803934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:47.262237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:46.891035Z","time spent":"371.121836ms","remote":"127.0.0.1:46044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:19:47.406579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.665306ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-03T19:19:47.406676Z","caller":"traceutil/trace.go:171","msg":"trace[1216039648] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:418; }","duration":"516.353214ms","start":"2026-07-03T19:19:46.890304Z","end":"2026-07-03T19:19:47.406658Z","steps":["trace[1216039648] 'read index received' (duration: 92.955294ms)","trace[1216039648] 'applied index is now lower than readState.Index' (duration: 423.39639ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:47.406837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.867453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-07-03T19:19:47.406873Z","caller":"traceutil/trace.go:171","msg":"trace[522739795] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:406; }","duration":"511.930535ms","start":"2026-07-03T19:19:46.894933Z","end":"2026-07-03T19:19:47.406864Z","steps":["trace[522739795] 'agreement among raft nodes before linearized reading' (duration: 511.835452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:47.406853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.525811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-5h7tw\" ","response":"range_response_count:1 size:4016"} {"level":"warn","ts":"2026-07-03T19:19:47.406875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.558438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T19:19:47.406902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:46.894922Z","time spent":"511.972297ms","remote":"127.0.0.1:46374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4218,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-07-03T19:19:47.406947Z","caller":"traceutil/trace.go:171","msg":"trace[2112423698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"373.676483ms","start":"2026-07-03T19:19:47.033252Z","end":"2026-07-03T19:19:47.406929Z","steps":["trace[2112423698] 'agreement among raft nodes before linearized reading' (duration: 373.559928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:47.406985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:47.033232Z","time spent":"373.744584ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-03T19:19:47.406935Z","caller":"traceutil/trace.go:171","msg":"trace[2146217546] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-5h7tw; range_end:; response_count:1; response_revision:406; }","duration":"516.658896ms","start":"2026-07-03T19:19:46.890259Z","end":"2026-07-03T19:19:47.406918Z","steps":["trace[2146217546] 'agreement among raft nodes before linearized reading' (duration: 516.49548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:47.407654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:46.890243Z","time spent":"517.398164ms","remote":"127.0.0.1:46134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4039,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-5h7tw\" "} {"level":"info","ts":"2026-07-03T19:19:47.55343Z","caller":"traceutil/trace.go:171","msg":"trace[716799816] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"143.859186ms","start":"2026-07-03T19:19:47.409546Z","end":"2026-07-03T19:19:47.553405Z","steps":["trace[716799816] 'process raft request' (duration: 143.713121ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:47.678559Z","caller":"traceutil/trace.go:171","msg":"trace[2040297337] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"263.055271ms","start":"2026-07-03T19:19:47.415478Z","end":"2026-07-03T19:19:47.678534Z","steps":["trace[2040297337] 'process raft request' (duration: 262.972798ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:47.678792Z","caller":"traceutil/trace.go:171","msg":"trace[1196851987] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"267.419677ms","start":"2026-07-03T19:19:47.411348Z","end":"2026-07-03T19:19:47.678767Z","steps":["trace[1196851987] 'process raft request' (duration: 248.167599ms)","trace[1196851987] 'compare' (duration: 18.746988ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:19:47.678967Z","caller":"traceutil/trace.go:171","msg":"trace[865453607] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"267.134516ms","start":"2026-07-03T19:19:47.411801Z","end":"2026-07-03T19:19:47.678936Z","steps":["trace[865453607] 'process raft request' (duration: 266.590255ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:47.71583Z","caller":"traceutil/trace.go:171","msg":"trace[1176861445] transaction","detail":"{read_only:false; number_of_response:1; response_revision:411; }","duration":"160.209094ms","start":"2026-07-03T19:19:47.5556Z","end":"2026-07-03T19:19:47.715809Z","steps":["trace[1176861445] 'process raft request' (duration: 160.08887ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:47.71607Z","caller":"traceutil/trace.go:171","msg":"trace[661629109] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"158.412057ms","start":"2026-07-03T19:19:47.557642Z","end":"2026-07-03T19:19:47.716054Z","steps":["trace[661629109] 'process raft request' (duration: 158.110115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:48.370473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.645911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:19:48.371173Z","caller":"traceutil/trace.go:171","msg":"trace[1451999394] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"684.922058ms","start":"2026-07-03T19:19:47.68623Z","end":"2026-07-03T19:19:48.371152Z","steps":["trace[1451999394] 'process raft request' (duration: 684.795164ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:48.371198Z","caller":"traceutil/trace.go:171","msg":"trace[1249497075] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"686.628662ms","start":"2026-07-03T19:19:47.68456Z","end":"2026-07-03T19:19:48.371189Z","steps":["trace[1249497075] 'process raft request' (duration: 686.364132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:48.371282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:47.686219Z","time spent":"685.015911ms","remote":"127.0.0.1:46374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:19:48.371308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:47.684552Z","time spent":"686.699114ms","remote":"127.0.0.1:46134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:19:48.371159Z","caller":"traceutil/trace.go:171","msg":"trace[196005750] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"686.762787ms","start":"2026-07-03T19:19:47.684372Z","end":"2026-07-03T19:19:48.371135Z","steps":["trace[196005750] 'process raft request' (duration: 175.381967ms)","trace[196005750] 'compare' (duration: 510.427293ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:48.371677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:47.684352Z","time spent":"687.164422ms","remote":"127.0.0.1:46044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:19:48.392661Z","caller":"traceutil/trace.go:171","msg":"trace[579666357] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"672.178137ms","start":"2026-07-03T19:19:47.720463Z","end":"2026-07-03T19:19:48.392641Z","steps":["trace[579666357] 'process raft request' (duration: 671.965039ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:48.392676Z","caller":"traceutil/trace.go:171","msg":"trace[736575531] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"672.02678ms","start":"2026-07-03T19:19:47.720633Z","end":"2026-07-03T19:19:48.392659Z","steps":["trace[736575531] 'process raft request' (duration: 671.912406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:48.392747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.88949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T19:19:48.392751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:47.720443Z","time spent":"672.27218ms","remote":"127.0.0.1:46044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:19:48.392782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:47.720625Z","time spent":"672.104433ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:19:48.39276Z","caller":"traceutil/trace.go:171","msg":"trace[2013464746] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"669.412451ms","start":"2026-07-03T19:19:47.723323Z","end":"2026-07-03T19:19:48.392735Z","steps":["trace[2013464746] 'process raft request' (duration: 669.271246ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:48.392789Z","caller":"traceutil/trace.go:171","msg":"trace[856474906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"358.972513ms","start":"2026-07-03T19:19:48.033807Z","end":"2026-07-03T19:19:48.39278Z","steps":["trace[856474906] 'agreement among raft nodes before linearized reading' (duration: 358.89845ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:48.392658Z","caller":"traceutil/trace.go:171","msg":"trace[1339253438] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:431; }","duration":"358.789965ms","start":"2026-07-03T19:19:48.033846Z","end":"2026-07-03T19:19:48.392636Z","steps":["trace[1339253438] 'read index received' (duration: 358.658681ms)","trace[1339253438] 'applied index is now lower than readState.Index' (duration: 130.034µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:48.392837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:48.033789Z","time spent":"359.041356ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T19:19:48.392976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:47.723309Z","time spent":"669.515906ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:19:48.7061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.266798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:19:48.706355Z","caller":"traceutil/trace.go:171","msg":"trace[961058] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"304.88639ms","start":"2026-07-03T19:19:48.401448Z","end":"2026-07-03T19:19:48.706334Z","steps":["trace[961058] 'process raft request' (duration: 304.761236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:48.706439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:48.401432Z","time spent":"304.959263ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:19:48.706702Z","caller":"traceutil/trace.go:171","msg":"trace[435223744] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"308.8404ms","start":"2026-07-03T19:19:48.397771Z","end":"2026-07-03T19:19:48.706612Z","steps":["trace[435223744] 'process raft request' (duration: 113.968068ms)","trace[435223744] 'compare' (duration: 194.011389ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:48.706944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:48.397756Z","time spent":"309.027277ms","remote":"127.0.0.1:46044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:19:49.406974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.560793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:19:49.407157Z","caller":"traceutil/trace.go:171","msg":"trace[863280225] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:439; }","duration":"373.046942ms","start":"2026-07-03T19:19:49.034094Z","end":"2026-07-03T19:19:49.407141Z","steps":["trace[863280225] 'read index received' (duration: 38.212µs)","trace[863280225] 'applied index is now lower than readState.Index' (duration: 373.00779ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:19:49.407242Z","caller":"traceutil/trace.go:171","msg":"trace[12091159] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"997.610764ms","start":"2026-07-03T19:19:48.40962Z","end":"2026-07-03T19:19:49.407231Z","steps":["trace[12091159] 'process raft request' (duration: 439.726489ms)","trace[12091159] 'compare' (duration: 557.370345ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:49.4073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:48.409606Z","time spent":"997.661138ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:19:49.407358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.257509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T19:19:49.407377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.656264ms","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-07-03T19:19:49.407417Z","caller":"traceutil/trace.go:171","msg":"trace[1115910578] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"995.518967ms","start":"2026-07-03T19:19:48.411886Z","end":"2026-07-03T19:19:49.407405Z","steps":["trace[1115910578] 'process raft request' (duration: 995.176264ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:49.407426Z","caller":"traceutil/trace.go:171","msg":"trace[509160891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:425; }","duration":"141.753227ms","start":"2026-07-03T19:19:49.265661Z","end":"2026-07-03T19:19:49.407414Z","steps":["trace[509160891] 'agreement among raft nodes before linearized reading' (duration: 141.655963ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:49.407421Z","caller":"traceutil/trace.go:171","msg":"trace[743319066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"373.362253ms","start":"2026-07-03T19:19:49.034042Z","end":"2026-07-03T19:19:49.407404Z","steps":["trace[743319066] 'agreement among raft nodes before linearized reading' (duration: 373.206847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:49.407462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:48.41187Z","time spent":"995.569779ms","remote":"127.0.0.1:46374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:19:49.407464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:49.033994Z","time spent":"373.462617ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-03T19:19:49.606515Z","caller":"traceutil/trace.go:171","msg":"trace[1798505384] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"127.320954ms","start":"2026-07-03T19:19:49.479166Z","end":"2026-07-03T19:19:49.606487Z","steps":["trace[1798505384] 'process raft request' (duration: 104.271223ms)","trace[1798505384] 'compare' (duration: 22.938687ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:19:50.54633Z","caller":"traceutil/trace.go:171","msg":"trace[1836858839] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"127.414743ms","start":"2026-07-03T19:19:50.418895Z","end":"2026-07-03T19:19:50.546309Z","steps":["trace[1836858839] 'read index received' (duration: 46.614562ms)","trace[1836858839] 'applied index is now lower than readState.Index' (duration: 80.799371ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:50.546439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.525057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-03T19:19:50.546471Z","caller":"traceutil/trace.go:171","msg":"trace[1541851544] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:432; }","duration":"127.59347ms","start":"2026-07-03T19:19:50.418867Z","end":"2026-07-03T19:19:50.546461Z","steps":["trace[1541851544] 'agreement among raft nodes before linearized reading' (duration: 127.524977ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:50.54643Z","caller":"traceutil/trace.go:171","msg":"trace[229326225] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"127.89627ms","start":"2026-07-03T19:19:50.418494Z","end":"2026-07-03T19:19:50.54639Z","steps":["trace[229326225] 'process raft request' (duration: 47.076148ms)","trace[229326225] 'compare' (duration: 80.653026ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:19:53.647221Z","caller":"traceutil/trace.go:171","msg":"trace[1230059175] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"176.599428ms","start":"2026-07-03T19:19:53.470558Z","end":"2026-07-03T19:19:53.647157Z","steps":["trace[1230059175] 'process raft request' (duration: 176.416372ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:55.927979Z","caller":"traceutil/trace.go:171","msg":"trace[891238158] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"116.619798ms","start":"2026-07-03T19:19:55.811333Z","end":"2026-07-03T19:19:55.927953Z","steps":["trace[891238158] 'process raft request' (duration: 67.779172ms)","trace[891238158] 'compare' (duration: 48.721372ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:19:57.666304Z","caller":"traceutil/trace.go:171","msg":"trace[1826100758] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"153.110075ms","start":"2026-07-03T19:19:57.513171Z","end":"2026-07-03T19:19:57.666281Z","steps":["trace[1826100758] 'process raft request' (duration: 152.777374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:57.853052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.450104ms","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-07-03T19:19:57.853123Z","caller":"traceutil/trace.go:171","msg":"trace[1027741120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:447; }","duration":"163.554897ms","start":"2026-07-03T19:19:57.689552Z","end":"2026-07-03T19:19:57.853106Z","steps":["trace[1027741120] 'range keys from in-memory index tree' (duration: 163.368931ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:57.853194Z","caller":"traceutil/trace.go:171","msg":"trace[1500253866] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"158.934172ms","start":"2026-07-03T19:19:57.694157Z","end":"2026-07-03T19:19:57.853091Z","steps":["trace[1500253866] 'process raft request' (duration: 158.897701ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:57.853349Z","caller":"traceutil/trace.go:171","msg":"trace[528777193] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"159.430519ms","start":"2026-07-03T19:19:57.693882Z","end":"2026-07-03T19:19:57.853313Z","steps":["trace[528777193] 'process raft request' (duration: 98.001723ms)","trace[528777193] 'compare' (duration: 61.071373ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:19:58.015606Z","caller":"traceutil/trace.go:171","msg":"trace[1658033823] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"157.132268ms","start":"2026-07-03T19:19:57.85843Z","end":"2026-07-03T19:19:58.015563Z","steps":["trace[1658033823] 'process raft request' (duration: 129.939383ms)","trace[1658033823] 'compare' (duration: 26.994358ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:19:58.015838Z","caller":"traceutil/trace.go:171","msg":"trace[1572806540] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"157.18446ms","start":"2026-07-03T19:19:57.858632Z","end":"2026-07-03T19:19:58.015816Z","steps":["trace[1572806540] 'process raft request' (duration: 156.855748ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:58.205943Z","caller":"traceutil/trace.go:171","msg":"trace[2111598500] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"104.430062ms","start":"2026-07-03T19:19:58.101486Z","end":"2026-07-03T19:19:58.205916Z","steps":["trace[2111598500] 'process raft request' (duration: 90.707544ms)","trace[2111598500] 'compare' (duration: 13.592234ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:19:58.207242Z","caller":"traceutil/trace.go:171","msg":"trace[1178481343] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"103.626164ms","start":"2026-07-03T19:19:58.103575Z","end":"2026-07-03T19:19:58.207201Z","steps":["trace[1178481343] 'process raft request' (duration: 103.303894ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:58.613093Z","caller":"traceutil/trace.go:171","msg":"trace[291119705] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"175.727775ms","start":"2026-07-03T19:19:58.437343Z","end":"2026-07-03T19:19:58.613071Z","steps":["trace[291119705] 'read index received' (duration: 125.082532ms)","trace[291119705] 'applied index is now lower than readState.Index' (duration: 50.644383ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:19:58.613138Z","caller":"traceutil/trace.go:171","msg":"trace[1511829410] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"245.946573ms","start":"2026-07-03T19:19:58.367159Z","end":"2026-07-03T19:19:58.613106Z","steps":["trace[1511829410] 'process raft request' (duration: 195.294779ms)","trace[1511829410] 'compare' (duration: 50.496808ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:58.613262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.221492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-07-03T19:19:58.613295Z","caller":"traceutil/trace.go:171","msg":"trace[1780596591] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:482; }","duration":"150.272464ms","start":"2026-07-03T19:19:58.463014Z","end":"2026-07-03T19:19:58.613286Z","steps":["trace[1780596591] 'agreement among raft nodes before linearized reading' (duration: 150.189271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:58.613592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.233162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-07-03T19:19:58.613666Z","caller":"traceutil/trace.go:171","msg":"trace[1364947181] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:482; }","duration":"176.344225ms","start":"2026-07-03T19:19:58.437302Z","end":"2026-07-03T19:19:58.613646Z","steps":["trace[1364947181] 'agreement among raft nodes before linearized reading' (duration: 175.87251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:58.995573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.67394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:19:58.995686Z","caller":"traceutil/trace.go:171","msg":"trace[441990270] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"304.610903ms","start":"2026-07-03T19:19:58.691056Z","end":"2026-07-03T19:19:58.995667Z","steps":["trace[441990270] 'process raft request' (duration: 50.760647ms)","trace[441990270] 'compare' (duration: 253.544936ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:19:58.995806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:58.691033Z","time spent":"304.743008ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:20:00.459264Z","caller":"traceutil/trace.go:171","msg":"trace[1026941479] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"440.551174ms","start":"2026-07-03T19:20:00.018692Z","end":"2026-07-03T19:20:00.459243Z","steps":["trace[1026941479] 'read index received' (duration: 440.386528ms)","trace[1026941479] 'applied index is now lower than readState.Index' (duration: 163.586µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:00.459374Z","caller":"traceutil/trace.go:171","msg":"trace[1544583309] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"510.643737ms","start":"2026-07-03T19:19:59.948696Z","end":"2026-07-03T19:20:00.45934Z","steps":["trace[1544583309] 'process raft request' (duration: 510.41492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:00.459423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.724039ms","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-07-03T19:20:00.459457Z","caller":"traceutil/trace.go:171","msg":"trace[145555774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:484; }","duration":"440.802932ms","start":"2026-07-03T19:20:00.018645Z","end":"2026-07-03T19:20:00.459448Z","steps":["trace[145555774] 'agreement among raft nodes before linearized reading' (duration: 440.72569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:00.459492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:00.01862Z","time spent":"440.864095ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T19:20:00.459735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.794036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-03T19:20:00.459808Z","caller":"traceutil/trace.go:171","msg":"trace[844968753] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:484; }","duration":"147.9136ms","start":"2026-07-03T19:20:00.311882Z","end":"2026-07-03T19:20:00.459795Z","steps":["trace[844968753] 'agreement among raft nodes before linearized reading' (duration: 147.790346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:00.45984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.277884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T19:20:00.459877Z","caller":"traceutil/trace.go:171","msg":"trace[1022555653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:484; }","duration":"425.356017ms","start":"2026-07-03T19:20:00.034508Z","end":"2026-07-03T19:20:00.459864Z","steps":["trace[1022555653] 'agreement among raft nodes before linearized reading' (duration: 425.295495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:00.459917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:00.034484Z","time spent":"425.426209ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T19:20:00.460105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:19:59.948672Z","time spent":"511.35103ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:20:00.459647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.922485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:20:00.460374Z","caller":"traceutil/trace.go:171","msg":"trace[135525515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"426.710121ms","start":"2026-07-03T19:20:00.033635Z","end":"2026-07-03T19:20:00.460345Z","steps":["trace[135525515] 'agreement among raft nodes before linearized reading' (duration: 425.915135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:00.460802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:00.033624Z","time spent":"427.159785ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-03T19:20:00.892138Z","caller":"traceutil/trace.go:171","msg":"trace[479942866] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"210.947112ms","start":"2026-07-03T19:20:00.681178Z","end":"2026-07-03T19:20:00.892125Z","steps":["trace[479942866] 'process raft request' (duration: 210.820658ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:00.892156Z","caller":"traceutil/trace.go:171","msg":"trace[1995019161] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"213.743593ms","start":"2026-07-03T19:20:00.678371Z","end":"2026-07-03T19:20:00.892115Z","steps":["trace[1995019161] 'process raft request' (duration: 213.569028ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:00.892404Z","caller":"traceutil/trace.go:171","msg":"trace[718033870] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"197.44962ms","start":"2026-07-03T19:20:00.694937Z","end":"2026-07-03T19:20:00.892387Z","steps":["trace[718033870] 'process raft request' (duration: 197.199742ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:00.892418Z","caller":"traceutil/trace.go:171","msg":"trace[1863120955] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"206.233457ms","start":"2026-07-03T19:20:00.686171Z","end":"2026-07-03T19:20:00.892404Z","steps":["trace[1863120955] 'process raft request' (duration: 205.876175ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:00.892424Z","caller":"traceutil/trace.go:171","msg":"trace[1049645162] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"180.397222ms","start":"2026-07-03T19:20:00.711975Z","end":"2026-07-03T19:20:00.892372Z","steps":["trace[1049645162] 'process raft request' (duration: 180.304689ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:00.89247Z","caller":"traceutil/trace.go:171","msg":"trace[1832968912] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"202.01647ms","start":"2026-07-03T19:20:00.690413Z","end":"2026-07-03T19:20:00.892429Z","steps":["trace[1832968912] 'process raft request' (duration: 201.683369ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:00.892522Z","caller":"traceutil/trace.go:171","msg":"trace[333051708] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"182.522141ms","start":"2026-07-03T19:20:00.709988Z","end":"2026-07-03T19:20:00.892511Z","steps":["trace[333051708] 'process raft request' (duration: 182.237032ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:00.892487Z","caller":"traceutil/trace.go:171","msg":"trace[702444600] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"190.038187ms","start":"2026-07-03T19:20:00.702406Z","end":"2026-07-03T19:20:00.892445Z","steps":["trace[702444600] 'process raft request' (duration: 189.767438ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:00.892762Z","caller":"traceutil/trace.go:171","msg":"trace[1182789506] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"218.586642ms","start":"2026-07-03T19:20:00.673487Z","end":"2026-07-03T19:20:00.892074Z","steps":["trace[1182789506] 'process raft request' (duration: 163.112307ms)","trace[1182789506] 'compare' (duration: 55.194506ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:00.960933Z","caller":"traceutil/trace.go:171","msg":"trace[1533091458] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"226.400328ms","start":"2026-07-03T19:20:00.734512Z","end":"2026-07-03T19:20:00.960912Z","steps":["trace[1533091458] 'process raft request' (duration: 226.345246ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:00.961046Z","caller":"traceutil/trace.go:171","msg":"trace[291596877] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"233.369595ms","start":"2026-07-03T19:20:00.72765Z","end":"2026-07-03T19:20:00.96102Z","steps":["trace[291596877] 'process raft request' (duration: 233.046085ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:00.961148Z","caller":"traceutil/trace.go:171","msg":"trace[2050753107] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"235.824815ms","start":"2026-07-03T19:20:00.72529Z","end":"2026-07-03T19:20:00.961115Z","steps":["trace[2050753107] 'process raft request' (duration: 235.292118ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:00.961149Z","caller":"traceutil/trace.go:171","msg":"trace[1625491154] transaction","detail":"{read_only:false; number_of_response:1; response_revision:499; }","duration":"233.317283ms","start":"2026-07-03T19:20:00.7278Z","end":"2026-07-03T19:20:00.961117Z","steps":["trace[1625491154] 'process raft request' (duration: 232.993183ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.061733Z","caller":"traceutil/trace.go:171","msg":"trace[1677369220] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"220.354311ms","start":"2026-07-03T19:20:00.841344Z","end":"2026-07-03T19:20:01.061698Z","steps":["trace[1677369220] 'process raft request' (duration: 158.711704ms)","trace[1677369220] 'compare' (duration: 61.43423ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:01.061797Z","caller":"traceutil/trace.go:171","msg":"trace[1298742261] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"212.927837ms","start":"2026-07-03T19:20:00.848846Z","end":"2026-07-03T19:20:01.061773Z","steps":["trace[1298742261] 'process raft request' (duration: 212.805554ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.061949Z","caller":"traceutil/trace.go:171","msg":"trace[1619255039] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"157.879476ms","start":"2026-07-03T19:20:00.904063Z","end":"2026-07-03T19:20:01.061943Z","steps":["trace[1619255039] 'process raft request' (duration: 157.723811ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.0621Z","caller":"traceutil/trace.go:171","msg":"trace[371704333] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"150.270907ms","start":"2026-07-03T19:20:00.911814Z","end":"2026-07-03T19:20:01.062085Z","steps":["trace[371704333] 'process raft request' (duration: 150.180695ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.062135Z","caller":"traceutil/trace.go:171","msg":"trace[858038238] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"160.480921ms","start":"2026-07-03T19:20:00.901639Z","end":"2026-07-03T19:20:01.06212Z","steps":["trace[858038238] 'process raft request' (duration: 160.109849ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.06222Z","caller":"traceutil/trace.go:171","msg":"trace[521008221] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"160.631536ms","start":"2026-07-03T19:20:00.901581Z","end":"2026-07-03T19:20:01.062212Z","steps":["trace[521008221] 'process raft request' (duration: 160.13896ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.06223Z","caller":"traceutil/trace.go:171","msg":"trace[52050839] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"157.70898ms","start":"2026-07-03T19:20:00.904511Z","end":"2026-07-03T19:20:01.06222Z","steps":["trace[52050839] 'process raft request' (duration: 157.299087ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.062352Z","caller":"traceutil/trace.go:171","msg":"trace[1686843753] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"156.690888ms","start":"2026-07-03T19:20:00.905584Z","end":"2026-07-03T19:20:01.062275Z","steps":["trace[1686843753] 'process raft request' (duration: 156.256074ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.062372Z","caller":"traceutil/trace.go:171","msg":"trace[1179326479] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"154.618149ms","start":"2026-07-03T19:20:00.907743Z","end":"2026-07-03T19:20:01.062361Z","steps":["trace[1179326479] 'process raft request' (duration: 154.142534ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.062408Z","caller":"traceutil/trace.go:171","msg":"trace[1269732128] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"152.420107ms","start":"2026-07-03T19:20:00.909971Z","end":"2026-07-03T19:20:01.062391Z","steps":["trace[1269732128] 'process raft request' (duration: 151.969573ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.248756Z","caller":"traceutil/trace.go:171","msg":"trace[634482674] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"175.350806ms","start":"2026-07-03T19:20:01.073385Z","end":"2026-07-03T19:20:01.248736Z","steps":["trace[634482674] 'process raft request' (duration: 90.259841ms)","trace[634482674] 'compare' (duration: 84.91795ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:01.249072Z","caller":"traceutil/trace.go:171","msg":"trace[1052473555] transaction","detail":"{read_only:false; number_of_response:1; response_revision:514; }","duration":"169.232249ms","start":"2026-07-03T19:20:01.079827Z","end":"2026-07-03T19:20:01.24906Z","steps":["trace[1052473555] 'process raft request' (duration: 168.866807ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.249086Z","caller":"traceutil/trace.go:171","msg":"trace[2035949402] transaction","detail":"{read_only:false; number_of_response:1; response_revision:514; }","duration":"168.980371ms","start":"2026-07-03T19:20:01.080077Z","end":"2026-07-03T19:20:01.249057Z","steps":["trace[2035949402] 'process raft request' (duration: 168.880907ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.25041Z","caller":"traceutil/trace.go:171","msg":"trace[682297879] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"139.820596ms","start":"2026-07-03T19:20:01.11057Z","end":"2026-07-03T19:20:01.250391Z","steps":["trace[682297879] 'process raft request' (duration: 139.555218ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.25066Z","caller":"traceutil/trace.go:171","msg":"trace[1983280663] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"131.92411ms","start":"2026-07-03T19:20:01.118721Z","end":"2026-07-03T19:20:01.250645Z","steps":["trace[1983280663] 'process raft request' (duration: 131.567519ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.250716Z","caller":"traceutil/trace.go:171","msg":"trace[794592375] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:534; }","duration":"138.063989ms","start":"2026-07-03T19:20:01.112633Z","end":"2026-07-03T19:20:01.250697Z","steps":["trace[794592375] 'read index received' (duration: 51.048883ms)","trace[794592375] 'applied index is now lower than readState.Index' (duration: 87.013746ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:01.250799Z","caller":"traceutil/trace.go:171","msg":"trace[850377780] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"130.185573ms","start":"2026-07-03T19:20:01.120602Z","end":"2026-07-03T19:20:01.250788Z","steps":["trace[850377780] 'process raft request' (duration: 129.928075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:01.250947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.279776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-07-03T19:20:01.251Z","caller":"traceutil/trace.go:171","msg":"trace[654771267] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:518; }","duration":"138.358308ms","start":"2026-07-03T19:20:01.112626Z","end":"2026-07-03T19:20:01.250984Z","steps":["trace[654771267] 'agreement among raft nodes before linearized reading' (duration: 138.139041ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.313472Z","caller":"traceutil/trace.go:171","msg":"trace[688018845] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"141.597504ms","start":"2026-07-03T19:20:01.171851Z","end":"2026-07-03T19:20:01.313449Z","steps":["trace[688018845] 'process raft request' (duration: 141.365297ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.458633Z","caller":"traceutil/trace.go:171","msg":"trace[486364747] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"133.015817ms","start":"2026-07-03T19:20:01.325581Z","end":"2026-07-03T19:20:01.458597Z","steps":["trace[486364747] 'process raft request' (duration: 44.798891ms)","trace[486364747] 'compare' (duration: 87.75611ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:01.458788Z","caller":"traceutil/trace.go:171","msg":"trace[864573433] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"108.260504ms","start":"2026-07-03T19:20:01.350486Z","end":"2026-07-03T19:20:01.458747Z","steps":["trace[864573433] 'process raft request' (duration: 107.997755ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.463455Z","caller":"traceutil/trace.go:171","msg":"trace[194651229] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"111.25415ms","start":"2026-07-03T19:20:01.352175Z","end":"2026-07-03T19:20:01.463429Z","steps":["trace[194651229] 'process raft request' (duration: 106.488937ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:01.649976Z","caller":"traceutil/trace.go:171","msg":"trace[1864572739] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"111.774798ms","start":"2026-07-03T19:20:01.538172Z","end":"2026-07-03T19:20:01.649947Z","steps":["trace[1864572739] 'process raft request' (duration: 70.300785ms)","trace[1864572739] 'compare' (duration: 41.249046ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:01.652223Z","caller":"traceutil/trace.go:171","msg":"trace[1145887666] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"109.899488ms","start":"2026-07-03T19:20:01.542306Z","end":"2026-07-03T19:20:01.652205Z","steps":["trace[1145887666] 'process raft request' (duration: 108.391669ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:02.334832Z","caller":"traceutil/trace.go:171","msg":"trace[475756124] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"142.644348ms","start":"2026-07-03T19:20:02.191755Z","end":"2026-07-03T19:20:02.334399Z","steps":["trace[475756124] 'process raft request' (duration: 98.585577ms)","trace[475756124] 'compare' (duration: 43.901006ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:02.417734Z","caller":"traceutil/trace.go:171","msg":"trace[642020354] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"135.299142ms","start":"2026-07-03T19:20:02.282405Z","end":"2026-07-03T19:20:02.417705Z","steps":["trace[642020354] 'process raft request' (duration: 135.24109ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:02.417811Z","caller":"traceutil/trace.go:171","msg":"trace[803069689] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"205.350626ms","start":"2026-07-03T19:20:02.212412Z","end":"2026-07-03T19:20:02.417762Z","steps":["trace[803069689] 'process raft request' (duration: 204.971184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:02.821549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.832101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:20:02.821889Z","caller":"traceutil/trace.go:171","msg":"trace[353793185] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:577; }","duration":"354.40924ms","start":"2026-07-03T19:20:02.467462Z","end":"2026-07-03T19:20:02.821871Z","steps":["trace[353793185] 'read index received' (duration: 104.225828ms)","trace[353793185] 'applied index is now lower than readState.Index' (duration: 250.182202ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:02.821902Z","caller":"traceutil/trace.go:171","msg":"trace[795966119] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"452.818492ms","start":"2026-07-03T19:20:02.369053Z","end":"2026-07-03T19:20:02.821872Z","steps":["trace[795966119] 'process raft request' (duration: 202.582818ms)","trace[795966119] 'compare' (duration: 249.672086ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:20:02.822055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:02.369015Z","time spent":"452.944487ms","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:20:02.822122Z","caller":"traceutil/trace.go:171","msg":"trace[1261516543] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"413.277116ms","start":"2026-07-03T19:20:02.40881Z","end":"2026-07-03T19:20:02.822087Z","steps":["trace[1261516543] 'process raft request' (duration: 412.845892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:02.822289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:02.408768Z","time spent":"413.436051ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:20:02.822576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.103362ms","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-07-03T19:20:02.822817Z","caller":"traceutil/trace.go:171","msg":"trace[847383309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"355.225276ms","start":"2026-07-03T19:20:02.467408Z","end":"2026-07-03T19:20:02.822633Z","steps":["trace[847383309] 'agreement among raft nodes before linearized reading' (duration: 354.793732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:02.822919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:02.467386Z","time spent":"355.483456ms","remote":"127.0.0.1:46208","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-07-03T19:20:02.827884Z","caller":"traceutil/trace.go:171","msg":"trace[1898100497] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"258.346144ms","start":"2026-07-03T19:20:02.569517Z","end":"2026-07-03T19:20:02.827863Z","steps":["trace[1898100497] 'process raft request' (duration: 257.952371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:02.879871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.620231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-03T19:20:02.879934Z","caller":"traceutil/trace.go:171","msg":"trace[338319770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"405.718714ms","start":"2026-07-03T19:20:02.4742Z","end":"2026-07-03T19:20:02.879918Z","steps":["trace[338319770] 'agreement among raft nodes before linearized reading' (duration: 405.535448ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:02.88003Z","caller":"traceutil/trace.go:171","msg":"trace[774663911] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"261.896998ms","start":"2026-07-03T19:20:02.618112Z","end":"2026-07-03T19:20:02.880009Z","steps":["trace[774663911] 'process raft request' (duration: 261.397592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:02.879961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:02.474192Z","time spent":"405.763125ms","remote":"127.0.0.1:46208","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-07-03T19:20:02.880254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.851297ms","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-07-03T19:20:02.88028Z","caller":"traceutil/trace.go:171","msg":"trace[1210650506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"404.925558ms","start":"2026-07-03T19:20:02.475348Z","end":"2026-07-03T19:20:02.880274Z","steps":["trace[1210650506] 'agreement among raft nodes before linearized reading' (duration: 404.878817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:02.880299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:02.475322Z","time spent":"404.97327ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-03T19:20:02.9681Z","caller":"traceutil/trace.go:171","msg":"trace[1030675417] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"119.652471ms","start":"2026-07-03T19:20:02.848424Z","end":"2026-07-03T19:20:02.968076Z","steps":["trace[1030675417] 'process raft request' (duration: 88.863875ms)","trace[1030675417] 'compare' (duration: 30.539428ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:03.14808Z","caller":"traceutil/trace.go:171","msg":"trace[1800358617] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"151.66428ms","start":"2026-07-03T19:20:02.996366Z","end":"2026-07-03T19:20:03.148031Z","steps":["trace[1800358617] 'process raft request' (duration: 99.861758ms)","trace[1800358617] 'compare' (duration: 51.477611ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:03.14813Z","caller":"traceutil/trace.go:171","msg":"trace[61686558] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:586; }","duration":"137.143426ms","start":"2026-07-03T19:20:03.010961Z","end":"2026-07-03T19:20:03.148105Z","steps":["trace[61686558] 'read index received' (duration: 85.452327ms)","trace[61686558] 'applied index is now lower than readState.Index' (duration: 51.690009ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:03.148323Z","caller":"traceutil/trace.go:171","msg":"trace[1089769919] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"139.572563ms","start":"2026-07-03T19:20:03.008685Z","end":"2026-07-03T19:20:03.148257Z","steps":["trace[1089769919] 'process raft request' (duration: 139.218142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:03.148624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.893479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:20:03.148668Z","caller":"traceutil/trace.go:171","msg":"trace[827385940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"113.982622ms","start":"2026-07-03T19:20:03.034673Z","end":"2026-07-03T19:20:03.148656Z","steps":["trace[827385940] 'agreement among raft nodes before linearized reading' (duration: 113.717354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:03.148786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.807096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-07-03T19:20:03.148872Z","caller":"traceutil/trace.go:171","msg":"trace[1173937694] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:566; }","duration":"137.935831ms","start":"2026-07-03T19:20:03.01092Z","end":"2026-07-03T19:20:03.148856Z","steps":["trace[1173937694] 'agreement among raft nodes before linearized reading' (duration: 137.443585ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:03.922616Z","caller":"traceutil/trace.go:171","msg":"trace[1088645258] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"195.467874ms","start":"2026-07-03T19:20:03.727122Z","end":"2026-07-03T19:20:03.92259Z","steps":["trace[1088645258] 'read index received' (duration: 195.02584ms)","trace[1088645258] 'applied index is now lower than readState.Index' (duration: 441.004µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:03.922706Z","caller":"traceutil/trace.go:171","msg":"trace[1580225250] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"361.135194ms","start":"2026-07-03T19:20:03.561529Z","end":"2026-07-03T19:20:03.922665Z","steps":["trace[1580225250] 'process raft request' (duration: 360.664129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:03.922897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:03.561501Z","time spent":"361.286929ms","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:20:03.922875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.685411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-07-03T19:20:03.923197Z","caller":"traceutil/trace.go:171","msg":"trace[746698790] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:571; }","duration":"196.091904ms","start":"2026-07-03T19:20:03.727079Z","end":"2026-07-03T19:20:03.923171Z","steps":["trace[746698790] 'agreement among raft nodes before linearized reading' (duration: 195.611128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:04.070234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.496471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:208485"} {"level":"info","ts":"2026-07-03T19:20:04.070321Z","caller":"traceutil/trace.go:171","msg":"trace[521670412] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:571; }","duration":"128.655706ms","start":"2026-07-03T19:20:03.941646Z","end":"2026-07-03T19:20:04.070302Z","steps":["trace[521670412] 'range keys from in-memory index tree' (duration: 128.12394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:05.48124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053785838767807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:20:05.982163Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053785838767807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:20:06.482772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053785838767807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:20:06.748342Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.859622507s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T19:20:06.748839Z","caller":"traceutil/trace.go:171","msg":"trace[910483259] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.860199345s","start":"2026-07-03T19:20:04.888617Z","end":"2026-07-03T19:20:06.748816Z","steps":["trace[910483259] 'process raft request' (duration: 1.86002438s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:06.74899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:04.888598Z","time spent":"1.860303769s","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:20:07.115893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.570179ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-03T19:20:07.116178Z","caller":"traceutil/trace.go:171","msg":"trace[160937050] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"2.135005999s","start":"2026-07-03T19:20:04.981141Z","end":"2026-07-03T19:20:07.116147Z","steps":["trace[160937050] 'read index received' (duration: 1.767674695s)","trace[160937050] 'applied index is now lower than readState.Index' (duration: 367.326454ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:20:07.116487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.135326168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T19:20:07.116531Z","caller":"traceutil/trace.go:171","msg":"trace[2077189031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"2.135426191s","start":"2026-07-03T19:20:04.981093Z","end":"2026-07-03T19:20:07.116519Z","steps":["trace[2077189031] 'agreement among raft nodes before linearized reading' (duration: 2.135196044s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:07.116527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.222103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T19:20:07.116575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:04.981069Z","time spent":"2.135495753s","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T19:20:07.116488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080869953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:20:07.116611Z","caller":"traceutil/trace.go:171","msg":"trace[322697098] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:572; }","duration":"315.337146ms","start":"2026-07-03T19:20:06.80125Z","end":"2026-07-03T19:20:07.116587Z","steps":["trace[322697098] 'agreement among raft nodes before linearized reading' (duration: 315.215982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:07.116652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:06.801234Z","time spent":"315.408939ms","remote":"127.0.0.1:46162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-07-03T19:20:07.11665Z","caller":"traceutil/trace.go:171","msg":"trace[333326561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"1.081080869s","start":"2026-07-03T19:20:06.035543Z","end":"2026-07-03T19:20:07.116624Z","steps":["trace[333326561] 'agreement among raft nodes before linearized reading' (duration: 1.080860983s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:07.116604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.754321749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-03T19:20:07.116709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:06.035521Z","time spent":"1.081176511s","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T19:20:07.116731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.135114482s","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-07-03T19:20:07.116817Z","caller":"traceutil/trace.go:171","msg":"trace[1958463844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"1.754471573s","start":"2026-07-03T19:20:05.36223Z","end":"2026-07-03T19:20:07.116702Z","steps":["trace[1958463844] 'agreement among raft nodes before linearized reading' (duration: 1.754202804s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:07.116823Z","caller":"traceutil/trace.go:171","msg":"trace[1614162112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"2.13539906s","start":"2026-07-03T19:20:04.981407Z","end":"2026-07-03T19:20:07.116806Z","steps":["trace[1614162112] 'agreement among raft nodes before linearized reading' (duration: 2.134971867s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:07.116935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:05.362216Z","time spent":"1.75470654s","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-03T19:20:07.116943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:04.981401Z","time spent":"2.135529003s","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-03T19:20:07.533168Z","caller":"traceutil/trace.go:171","msg":"trace[1210253107] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"278.344188ms","start":"2026-07-03T19:20:07.254796Z","end":"2026-07-03T19:20:07.53314Z","steps":["trace[1210253107] 'process raft request' (duration: 278.201634ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:10.592369Z","caller":"traceutil/trace.go:171","msg":"trace[520150603] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"279.65972ms","start":"2026-07-03T19:20:10.312616Z","end":"2026-07-03T19:20:10.592276Z","steps":["trace[520150603] 'read index received' (duration: 264.750501ms)","trace[520150603] 'applied index is now lower than readState.Index' (duration: 14.906689ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:20:10.592711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.081653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-03T19:20:10.59276Z","caller":"traceutil/trace.go:171","msg":"trace[1353554533] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:591; }","duration":"280.192165ms","start":"2026-07-03T19:20:10.31255Z","end":"2026-07-03T19:20:10.592742Z","steps":["trace[1353554533] 'agreement among raft nodes before linearized reading' (duration: 279.937938ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:10.592944Z","caller":"traceutil/trace.go:171","msg":"trace[65274307] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"489.786551ms","start":"2026-07-03T19:20:10.103107Z","end":"2026-07-03T19:20:10.592893Z","steps":["trace[65274307] 'process raft request' (duration: 474.337716ms)","trace[65274307] 'compare' (duration: 12.675283ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:20:10.593093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:10.103073Z","time spent":"489.938685ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:20:13.333217Z","caller":"traceutil/trace.go:171","msg":"trace[849643403] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"104.216759ms","start":"2026-07-03T19:20:13.228973Z","end":"2026-07-03T19:20:13.33319Z","steps":["trace[849643403] 'process raft request' (duration: 36.764101ms)","trace[849643403] 'compare' (duration: 67.268583ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:13.33334Z","caller":"traceutil/trace.go:171","msg":"trace[1403891199] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"102.465239ms","start":"2026-07-03T19:20:13.230859Z","end":"2026-07-03T19:20:13.333324Z","steps":["trace[1403891199] 'process raft request' (duration: 102.282813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:15.717971Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053785838767911,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:20:16.115844Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.007313444s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T19:20:16.116322Z","caller":"traceutil/trace.go:171","msg":"trace[48870911] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"899.002359ms","start":"2026-07-03T19:20:15.217295Z","end":"2026-07-03T19:20:16.116297Z","steps":["trace[48870911] 'read index received' (duration: 898.853585ms)","trace[48870911] 'applied index is now lower than readState.Index' (duration: 146.964µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:16.116451Z","caller":"traceutil/trace.go:171","msg":"trace[1734418402] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.007953031s","start":"2026-07-03T19:20:15.108431Z","end":"2026-07-03T19:20:16.116384Z","steps":["trace[1734418402] 'process raft request' (duration: 1.007685934s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:16.116577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.266656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-03T19:20:16.116617Z","caller":"traceutil/trace.go:171","msg":"trace[77429358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"899.381849ms","start":"2026-07-03T19:20:15.217225Z","end":"2026-07-03T19:20:16.116607Z","steps":["trace[77429358] 'agreement among raft nodes before linearized reading' (duration: 899.184244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:16.116658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:15.217172Z","time spent":"899.477041ms","remote":"127.0.0.1:46208","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-07-03T19:20:16.116771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:15.108418Z","time spent":"1.008195398s","remote":"127.0.0.1:46134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:20:16.572501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.04685ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-03T19:20:16.572827Z","caller":"traceutil/trace.go:171","msg":"trace[1888135267] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"444.261556ms","start":"2026-07-03T19:20:16.128529Z","end":"2026-07-03T19:20:16.572791Z","steps":["trace[1888135267] 'process raft request' (duration: 444.112482ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:16.57279Z","caller":"traceutil/trace.go:171","msg":"trace[636689092] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"456.347981ms","start":"2026-07-03T19:20:16.116385Z","end":"2026-07-03T19:20:16.572733Z","steps":["trace[636689092] 'read index received' (duration: 34.059725ms)","trace[636689092] 'applied index is now lower than readState.Index' (duration: 422.282396ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:20:16.572973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:16.128503Z","time spent":"444.382129ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:20:16.57298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233281278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-03T19:20:16.573076Z","caller":"traceutil/trace.go:171","msg":"trace[491138569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"1.233440323s","start":"2026-07-03T19:20:15.339616Z","end":"2026-07-03T19:20:16.573056Z","steps":["trace[491138569] 'agreement among raft nodes before linearized reading' (duration: 1.233162425s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:16.573112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:15.33958Z","time spent":"1.233525345s","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-03T19:20:16.573025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.854795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:20:16.573215Z","caller":"traceutil/trace.go:171","msg":"trace[826935112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"537.115902ms","start":"2026-07-03T19:20:16.036044Z","end":"2026-07-03T19:20:16.57316Z","steps":["trace[826935112] 'agreement among raft nodes before linearized reading' (duration: 536.721191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:16.573234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2351058s","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-07-03T19:20:16.573283Z","caller":"traceutil/trace.go:171","msg":"trace[265605872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"1.235287165s","start":"2026-07-03T19:20:15.33798Z","end":"2026-07-03T19:20:16.573267Z","steps":["trace[265605872] 'agreement among raft nodes before linearized reading' (duration: 1.234930895s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:16.573341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:15.337958Z","time spent":"1.235373247s","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T19:20:16.573502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:16.03602Z","time spent":"537.464842ms","remote":"127.0.0.1:45958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-03T19:20:17.303608Z","caller":"traceutil/trace.go:171","msg":"trace[550868957] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"725.734553ms","start":"2026-07-03T19:20:16.577848Z","end":"2026-07-03T19:20:17.303582Z","steps":["trace[550868957] 'process raft request' (duration: 725.538118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:17.303871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:16.57782Z","time spent":"725.901667ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:20:17.334535Z","caller":"traceutil/trace.go:171","msg":"trace[422795603] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"479.729971ms","start":"2026-07-03T19:20:16.854781Z","end":"2026-07-03T19:20:17.334511Z","steps":["trace[422795603] 'read index received' (duration: 448.558905ms)","trace[422795603] 'applied index is now lower than readState.Index' (duration: 31.170026ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:17.334629Z","caller":"traceutil/trace.go:171","msg":"trace[1510360705] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"756.167829ms","start":"2026-07-03T19:20:16.578415Z","end":"2026-07-03T19:20:17.334583Z","steps":["trace[1510360705] 'process raft request' (duration: 755.970974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:17.334742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.092545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T19:20:17.334778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.994858ms","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-07-03T19:20:17.334804Z","caller":"traceutil/trace.go:171","msg":"trace[662872261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"300.210768ms","start":"2026-07-03T19:20:17.034578Z","end":"2026-07-03T19:20:17.334789Z","steps":["trace[662872261] 'agreement among raft nodes before linearized reading' (duration: 300.101385ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:17.334816Z","caller":"traceutil/trace.go:171","msg":"trace[328624928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"480.08556ms","start":"2026-07-03T19:20:16.854719Z","end":"2026-07-03T19:20:17.334804Z","steps":["trace[328624928] 'agreement among raft nodes before linearized reading' (duration: 479.914736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:17.334848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:17.034557Z","time spent":"300.282429ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T19:20:17.334848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:16.854695Z","time spent":"480.144552ms","remote":"127.0.0.1:46208","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-07-03T19:20:17.334848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:16.578402Z","time spent":"756.324564ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:20:20.275976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.890902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:20:20.276038Z","caller":"traceutil/trace.go:171","msg":"trace[1818439669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"240.009245ms","start":"2026-07-03T19:20:20.036017Z","end":"2026-07-03T19:20:20.276026Z","steps":["trace[1818439669] 'range keys from in-memory index tree' (duration: 239.7954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:20.276082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.820661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T19:20:20.276147Z","caller":"traceutil/trace.go:171","msg":"trace[1633935006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:622; }","duration":"936.953434ms","start":"2026-07-03T19:20:19.339176Z","end":"2026-07-03T19:20:20.276129Z","steps":["trace[1633935006] 'range keys from in-memory index tree' (duration: 936.654966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:20.276176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:19.339142Z","time spent":"937.026396ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T19:20:20.276341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.277972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:20:20.27636Z","caller":"traceutil/trace.go:171","msg":"trace[987833027] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:622; }","duration":"289.350165ms","start":"2026-07-03T19:20:19.987003Z","end":"2026-07-03T19:20:20.276353Z","steps":["trace[987833027] 'count revisions from in-memory index tree' (duration: 289.122708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:20.2764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.428192ms","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-07-03T19:20:20.27643Z","caller":"traceutil/trace.go:171","msg":"trace[436626704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"968.483783ms","start":"2026-07-03T19:20:19.307936Z","end":"2026-07-03T19:20:20.27642Z","steps":["trace[436626704] 'range keys from in-memory index tree' (duration: 968.311328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:20.27645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:19.30792Z","time spent":"968.525354ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-03T19:20:20.276537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.241848645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:20:20.276556Z","caller":"traceutil/trace.go:171","msg":"trace[776784369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"2.241909508s","start":"2026-07-03T19:20:18.03464Z","end":"2026-07-03T19:20:20.27655Z","steps":["trace[776784369] 'range keys from in-memory index tree' (duration: 2.241769364s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:20.276582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:18.034612Z","time spent":"2.241963348s","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T19:20:20.276726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.745228ms","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-07-03T19:20:20.276743Z","caller":"traceutil/trace.go:171","msg":"trace[402833573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"926.80651ms","start":"2026-07-03T19:20:19.34993Z","end":"2026-07-03T19:20:20.276737Z","steps":["trace[402833573] 'range keys from in-memory index tree' (duration: 926.618935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:20.276759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:19.349908Z","time spent":"926.847421ms","remote":"127.0.0.1:46208","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-07-03T19:20:20.276776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.699062589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-03T19:20:20.276704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.407533ms","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-07-03T19:20:20.276822Z","caller":"traceutil/trace.go:171","msg":"trace[588758719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"1.699152612s","start":"2026-07-03T19:20:18.577656Z","end":"2026-07-03T19:20:20.276808Z","steps":["trace[588758719] 'range keys from in-memory index tree' (duration: 1.698875144s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:20.276886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:18.577635Z","time spent":"1.699236933s","remote":"127.0.0.1:46208","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-07-03T19:20:20.276886Z","caller":"traceutil/trace.go:171","msg":"trace[847102330] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:622; }","duration":"928.617189ms","start":"2026-07-03T19:20:19.348243Z","end":"2026-07-03T19:20:20.27686Z","steps":["trace[847102330] 'range keys from in-memory index tree' (duration: 928.382223ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:22.706438Z","caller":"traceutil/trace.go:171","msg":"trace[2092775899] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"422.782298ms","start":"2026-07-03T19:20:22.283626Z","end":"2026-07-03T19:20:22.706408Z","steps":["trace[2092775899] 'process raft request' (duration: 422.622284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:22.706588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:22.283584Z","time spent":"422.934532ms","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:20:22.70644Z","caller":"traceutil/trace.go:171","msg":"trace[555282291] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:680; }","duration":"405.620039ms","start":"2026-07-03T19:20:22.300782Z","end":"2026-07-03T19:20:22.706402Z","steps":["trace[555282291] 'read index received' (duration: 405.608289ms)","trace[555282291] 'applied index is now lower than readState.Index' (duration: 9.67µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:20:22.706771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.983409ms","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-07-03T19:20:22.706874Z","caller":"traceutil/trace.go:171","msg":"trace[1731428468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:652; }","duration":"406.131062ms","start":"2026-07-03T19:20:22.300711Z","end":"2026-07-03T19:20:22.706842Z","steps":["trace[1731428468] 'agreement among raft nodes before linearized reading' (duration: 405.783473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:22.706917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:22.30068Z","time spent":"406.228685ms","remote":"127.0.0.1:46208","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-07-03T19:20:22.713236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.41523ms","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-07-03T19:20:22.713287Z","caller":"traceutil/trace.go:171","msg":"trace[961063026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"401.514412ms","start":"2026-07-03T19:20:22.31176Z","end":"2026-07-03T19:20:22.713274Z","steps":["trace[961063026] 'agreement among raft nodes before linearized reading' (duration: 401.308317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:22.713325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:22.311749Z","time spent":"401.568393ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-03T19:20:22.713285Z","caller":"traceutil/trace.go:171","msg":"trace[1479105911] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"230.176076ms","start":"2026-07-03T19:20:22.483079Z","end":"2026-07-03T19:20:22.713255Z","steps":["trace[1479105911] 'process raft request' (duration: 229.825537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:22.713637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.282069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T19:20:22.713698Z","caller":"traceutil/trace.go:171","msg":"trace[974537151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"400.362721ms","start":"2026-07-03T19:20:22.313322Z","end":"2026-07-03T19:20:22.713685Z","steps":["trace[974537151] 'agreement among raft nodes before linearized reading' (duration: 400.105864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:22.713716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.532776ms","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-07-03T19:20:22.713828Z","caller":"traceutil/trace.go:171","msg":"trace[34604208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"400.670119ms","start":"2026-07-03T19:20:22.313127Z","end":"2026-07-03T19:20:22.713797Z","steps":["trace[34604208] 'agreement among raft nodes before linearized reading' (duration: 400.33051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:22.713886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:22.313117Z","time spent":"400.758282ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T19:20:22.714386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:22.313313Z","time spent":"400.453554ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-03T19:20:25.569477Z","caller":"traceutil/trace.go:171","msg":"trace[2089716744] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"283.108986ms","start":"2026-07-03T19:20:25.286337Z","end":"2026-07-03T19:20:25.569446Z","steps":["trace[2089716744] 'process raft request' (duration: 282.572022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:34.040641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.55315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:20:34.041012Z","caller":"traceutil/trace.go:171","msg":"trace[163663525] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"651.533012ms","start":"2026-07-03T19:20:33.389463Z","end":"2026-07-03T19:20:34.040996Z","steps":["trace[163663525] 'process raft request' (duration: 136.523401ms)","trace[163663525] 'compare' (duration: 514.452838ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:20:34.041066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:33.38943Z","time spent":"651.611983ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:20:34.041163Z","caller":"traceutil/trace.go:171","msg":"trace[1456585672] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"528.464398ms","start":"2026-07-03T19:20:33.512678Z","end":"2026-07-03T19:20:34.041143Z","steps":["trace[1456585672] 'process raft request' (duration: 528.049889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:34.041258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:33.512646Z","time spent":"528.567961ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:20:37.334521Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053785838768332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:20:37.83535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053785838768332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:20:38.224114Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.419165444s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T19:20:38.224386Z","caller":"traceutil/trace.go:171","msg":"trace[490276436] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"1.419547763s","start":"2026-07-03T19:20:36.80482Z","end":"2026-07-03T19:20:38.224368Z","steps":["trace[490276436] 'process raft request' (duration: 1.419448841s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:38.224512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:36.804796Z","time spent":"1.419649974s","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:20:38.500294Z","caller":"traceutil/trace.go:171","msg":"trace[1170021710] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"1.665805682s","start":"2026-07-03T19:20:36.834464Z","end":"2026-07-03T19:20:38.50027Z","steps":["trace[1170021710] 'read index received' (duration: 1.390132095s)","trace[1170021710] 'applied index is now lower than readState.Index' (duration: 275.672547ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:38.500383Z","caller":"traceutil/trace.go:171","msg":"trace[653631336] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"1.273928192s","start":"2026-07-03T19:20:37.22643Z","end":"2026-07-03T19:20:38.500358Z","steps":["trace[653631336] 'process raft request' (duration: 1.272894469s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:38.500584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666101038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-03T19:20:38.500599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:37.226404Z","time spent":"1.274091246s","remote":"127.0.0.1:46196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:20:38.500645Z","caller":"traceutil/trace.go:171","msg":"trace[1127788470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"1.666221241s","start":"2026-07-03T19:20:36.834406Z","end":"2026-07-03T19:20:38.500627Z","steps":["trace[1127788470] 'agreement among raft nodes before linearized reading' (duration: 1.666028727s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:38.500697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:36.834388Z","time spent":"1.666296273s","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T19:20:38.500782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.662636388s","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-07-03T19:20:38.500861Z","caller":"traceutil/trace.go:171","msg":"trace[462354735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:785; }","duration":"1.662748261s","start":"2026-07-03T19:20:36.838095Z","end":"2026-07-03T19:20:38.500843Z","steps":["trace[462354735] 'agreement among raft nodes before linearized reading' (duration: 1.662543926s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:38.500912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:36.838083Z","time spent":"1.662821522s","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T19:20:38.50107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.456408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:20:38.501103Z","caller":"traceutil/trace.go:171","msg":"trace[1743934797] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:785; }","duration":"311.54788ms","start":"2026-07-03T19:20:38.189547Z","end":"2026-07-03T19:20:38.501094Z","steps":["trace[1743934797] 'agreement among raft nodes before linearized reading' (duration: 311.464709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:38.501136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:38.189533Z","time spent":"311.591371ms","remote":"127.0.0.1:46304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-03T19:20:38.501082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.970837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-03T19:20:38.500964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.044042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:20:38.501274Z","caller":"traceutil/trace.go:171","msg":"trace[998467280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:785; }","duration":"341.260683ms","start":"2026-07-03T19:20:38.159987Z","end":"2026-07-03T19:20:38.501247Z","steps":["trace[998467280] 'agreement among raft nodes before linearized reading' (duration: 340.796982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:38.501176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66232274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-03T19:20:38.50135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:38.15996Z","time spent":"341.375627ms","remote":"127.0.0.1:46208","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-07-03T19:20:38.501377Z","caller":"traceutil/trace.go:171","msg":"trace[1679114934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"1.662567107s","start":"2026-07-03T19:20:36.838784Z","end":"2026-07-03T19:20:38.501351Z","steps":["trace[1679114934] 'agreement among raft nodes before linearized reading' (duration: 1.662118696s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:38.501442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:36.838773Z","time spent":"1.662653829s","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-03T19:20:38.501636Z","caller":"traceutil/trace.go:171","msg":"trace[885218754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"466.777898ms","start":"2026-07-03T19:20:38.034848Z","end":"2026-07-03T19:20:38.501626Z","steps":["trace[885218754] 'agreement among raft nodes before linearized reading' (duration: 465.99392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:38.501669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:38.034829Z","time spent":"466.83292ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-03T19:20:47.488339Z","caller":"traceutil/trace.go:171","msg":"trace[426638476] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"199.860466ms","start":"2026-07-03T19:20:47.288443Z","end":"2026-07-03T19:20:47.488304Z","steps":["trace[426638476] 'process raft request' (duration: 199.521088ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:47.517464Z","caller":"traceutil/trace.go:171","msg":"trace[2092726572] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"185.911101ms","start":"2026-07-03T19:20:47.331503Z","end":"2026-07-03T19:20:47.517415Z","steps":["trace[2092726572] 'process raft request' (duration: 185.681006ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:47.669653Z","caller":"traceutil/trace.go:171","msg":"trace[579489777] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"173.436319ms","start":"2026-07-03T19:20:47.496192Z","end":"2026-07-03T19:20:47.669629Z","steps":["trace[579489777] 'process raft request' (duration: 173.375138ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:47.66966Z","caller":"traceutil/trace.go:171","msg":"trace[1458788986] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"173.679124ms","start":"2026-07-03T19:20:47.495946Z","end":"2026-07-03T19:20:47.669625Z","steps":["trace[1458788986] 'process raft request' (duration: 171.940198ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:47.762274Z","caller":"traceutil/trace.go:171","msg":"trace[946340547] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"237.055957ms","start":"2026-07-03T19:20:47.525194Z","end":"2026-07-03T19:20:47.76225Z","steps":["trace[946340547] 'process raft request' (duration: 236.915464ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:47.894087Z","caller":"traceutil/trace.go:171","msg":"trace[1783309807] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"122.601128ms","start":"2026-07-03T19:20:47.771469Z","end":"2026-07-03T19:20:47.89407Z","steps":["trace[1783309807] 'process raft request' (duration: 122.431105ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:47.894147Z","caller":"traceutil/trace.go:171","msg":"trace[684216573] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"122.19575ms","start":"2026-07-03T19:20:47.771921Z","end":"2026-07-03T19:20:47.894117Z","steps":["trace[684216573] 'process raft request' (duration: 122.076518ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:47.894254Z","caller":"traceutil/trace.go:171","msg":"trace[1972966233] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:915; }","duration":"114.12424ms","start":"2026-07-03T19:20:47.780101Z","end":"2026-07-03T19:20:47.894225Z","steps":["trace[1972966233] 'read index received' (duration: 37.221063ms)","trace[1972966233] 'applied index is now lower than readState.Index' (duration: 76.901177ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:47.894348Z","caller":"traceutil/trace.go:171","msg":"trace[1853341680] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"120.22439ms","start":"2026-07-03T19:20:47.774118Z","end":"2026-07-03T19:20:47.894342Z","steps":["trace[1853341680] 'process raft request' (duration: 119.919252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:47.894424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.295305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:20:47.89446Z","caller":"traceutil/trace.go:171","msg":"trace[974259819] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:885; }","duration":"114.391157ms","start":"2026-07-03T19:20:47.780058Z","end":"2026-07-03T19:20:47.89445Z","steps":["trace[974259819] 'agreement among raft nodes before linearized reading' (duration: 114.293415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:47.894587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.621662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:20:47.894645Z","caller":"traceutil/trace.go:171","msg":"trace[989624827] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:885; }","duration":"104.718734ms","start":"2026-07-03T19:20:47.78991Z","end":"2026-07-03T19:20:47.894629Z","steps":["trace[989624827] 'agreement among raft nodes before linearized reading' (duration: 104.615062ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:48.116191Z","caller":"traceutil/trace.go:171","msg":"trace[1982577006] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"215.885493ms","start":"2026-07-03T19:20:47.900286Z","end":"2026-07-03T19:20:48.116172Z","steps":["trace[1982577006] 'process raft request' (duration: 215.276129ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:48.116732Z","caller":"traceutil/trace.go:171","msg":"trace[2009102060] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"216.254931ms","start":"2026-07-03T19:20:47.900459Z","end":"2026-07-03T19:20:48.116713Z","steps":["trace[2009102060] 'process raft request' (duration: 215.984106ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:48.117234Z","caller":"traceutil/trace.go:171","msg":"trace[539811092] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"214.369112ms","start":"2026-07-03T19:20:47.902302Z","end":"2026-07-03T19:20:48.116671Z","steps":["trace[539811092] 'process raft request' (duration: 214.291081ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:48.331365Z","caller":"traceutil/trace.go:171","msg":"trace[1516077289] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"209.915832ms","start":"2026-07-03T19:20:48.121425Z","end":"2026-07-03T19:20:48.331341Z","steps":["trace[1516077289] 'process raft request' (duration: 206.123352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:48.568863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.408254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:20:48.569038Z","caller":"traceutil/trace.go:171","msg":"trace[1466111441] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:923; }","duration":"282.269181ms","start":"2026-07-03T19:20:48.286752Z","end":"2026-07-03T19:20:48.569021Z","steps":["trace[1466111441] 'read index received' (duration: 40.812971ms)","trace[1466111441] 'applied index is now lower than readState.Index' (duration: 241.45533ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:48.569125Z","caller":"traceutil/trace.go:171","msg":"trace[1741000622] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"447.00947ms","start":"2026-07-03T19:20:48.122105Z","end":"2026-07-03T19:20:48.569114Z","steps":["trace[1741000622] 'process raft request' (duration: 446.849937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:48.569194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:48.122095Z","time spent":"447.058781ms","remote":"127.0.0.1:46374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:20:48.569299Z","caller":"traceutil/trace.go:171","msg":"trace[496930094] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"447.798366ms","start":"2026-07-03T19:20:48.121471Z","end":"2026-07-03T19:20:48.56927Z","steps":["trace[496930094] 'process raft request' (duration: 337.914193ms)","trace[496930094] 'compare' (duration: 109.291151ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:20:48.569348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.562437ms","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-07-03T19:20:48.569404Z","caller":"traceutil/trace.go:171","msg":"trace[1364206284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:892; }","duration":"282.67152ms","start":"2026-07-03T19:20:48.286718Z","end":"2026-07-03T19:20:48.569389Z","steps":["trace[1364206284] 'agreement among raft nodes before linearized reading' (duration: 282.466836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:48.569429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:20:48.121455Z","time spent":"447.915828ms","remote":"127.0.0.1:46044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:20:48.585862Z","caller":"traceutil/trace.go:171","msg":"trace[1293748722] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"248.101788ms","start":"2026-07-03T19:20:48.337748Z","end":"2026-07-03T19:20:48.585849Z","steps":["trace[1293748722] 'process raft request' (duration: 248.077088ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:48.58611Z","caller":"traceutil/trace.go:171","msg":"trace[1361280489] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"250.742854ms","start":"2026-07-03T19:20:48.335358Z","end":"2026-07-03T19:20:48.586101Z","steps":["trace[1361280489] 'process raft request' (duration: 250.285314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:48.586077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.77852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:20:48.586175Z","caller":"traceutil/trace.go:171","msg":"trace[1490627635] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:897; }","duration":"165.907953ms","start":"2026-07-03T19:20:48.420251Z","end":"2026-07-03T19:20:48.586159Z","steps":["trace[1490627635] 'agreement among raft nodes before linearized reading' (duration: 165.75249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:48.586304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.670393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-256fb\" ","response":"range_response_count:1 size:4881"} {"level":"info","ts":"2026-07-03T19:20:48.586386Z","caller":"traceutil/trace.go:171","msg":"trace[77848208] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-256fb; range_end:; response_count:1; response_revision:897; }","duration":"249.790075ms","start":"2026-07-03T19:20:48.336578Z","end":"2026-07-03T19:20:48.586368Z","steps":["trace[77848208] 'agreement among raft nodes before linearized reading' (duration: 249.544639ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:48.586404Z","caller":"traceutil/trace.go:171","msg":"trace[917367277] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"248.952477ms","start":"2026-07-03T19:20:48.337443Z","end":"2026-07-03T19:20:48.586396Z","steps":["trace[917367277] 'process raft request' (duration: 248.349053ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:48.586479Z","caller":"traceutil/trace.go:171","msg":"trace[342235048] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"250.918198ms","start":"2026-07-03T19:20:48.335541Z","end":"2026-07-03T19:20:48.586459Z","steps":["trace[342235048] 'process raft request' (duration: 250.173762ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:48.586723Z","caller":"traceutil/trace.go:171","msg":"trace[1539233847] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"250.065561ms","start":"2026-07-03T19:20:48.336628Z","end":"2026-07-03T19:20:48.586693Z","steps":["trace[1539233847] 'process raft request' (duration: 249.1271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:48.586986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.761005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:20:48.587024Z","caller":"traceutil/trace.go:171","msg":"trace[871756122] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:897; }","duration":"148.851787ms","start":"2026-07-03T19:20:48.438164Z","end":"2026-07-03T19:20:48.587015Z","steps":["trace[871756122] 'agreement among raft nodes before linearized reading' (duration: 148.627423ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:48.738357Z","caller":"traceutil/trace.go:171","msg":"trace[1959171313] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"146.202772ms","start":"2026-07-03T19:20:48.592135Z","end":"2026-07-03T19:20:48.738337Z","steps":["trace[1959171313] 'process raft request' (duration: 97.460564ms)","trace[1959171313] 'compare' (duration: 48.610935ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:48.738774Z","caller":"traceutil/trace.go:171","msg":"trace[226944777] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"144.813303ms","start":"2026-07-03T19:20:48.593947Z","end":"2026-07-03T19:20:48.73876Z","steps":["trace[226944777] 'process raft request' (duration: 144.555218ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:48.738806Z","caller":"traceutil/trace.go:171","msg":"trace[1249795939] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"146.524108ms","start":"2026-07-03T19:20:48.592265Z","end":"2026-07-03T19:20:48.738789Z","steps":["trace[1249795939] 'process raft request' (duration: 146.027758ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:48.9339Z","caller":"traceutil/trace.go:171","msg":"trace[594638309] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"187.052625ms","start":"2026-07-03T19:20:48.746823Z","end":"2026-07-03T19:20:48.933876Z","steps":["trace[594638309] 'read index received' (duration: 186.103664ms)","trace[594638309] 'applied index is now lower than readState.Index' (duration: 947.821µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:20:48.934002Z","caller":"traceutil/trace.go:171","msg":"trace[1892475463] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"187.425483ms","start":"2026-07-03T19:20:48.746542Z","end":"2026-07-03T19:20:48.933967Z","steps":["trace[1892475463] 'process raft request' (duration: 186.451712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:20:48.934002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.159447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:20:48.934236Z","caller":"traceutil/trace.go:171","msg":"trace[1675225918] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:905; }","duration":"187.400892ms","start":"2026-07-03T19:20:48.746818Z","end":"2026-07-03T19:20:48.934219Z","steps":["trace[1675225918] 'agreement among raft nodes before linearized reading' (duration: 187.137287ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:20:53.797997Z","caller":"traceutil/trace.go:171","msg":"trace[1856661922] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"259.709385ms","start":"2026-07-03T19:20:53.538271Z","end":"2026-07-03T19:20:53.79798Z","steps":["trace[1856661922] 'process raft request' (duration: 259.607613ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:10.904827Z","caller":"traceutil/trace.go:171","msg":"trace[1980945753] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"123.226173ms","start":"2026-07-03T19:21:10.781574Z","end":"2026-07-03T19:21:10.9048Z","steps":["trace[1980945753] 'process raft request' (duration: 122.941468ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:11.089023Z","caller":"traceutil/trace.go:171","msg":"trace[94436763] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"179.640988ms","start":"2026-07-03T19:21:10.90935Z","end":"2026-07-03T19:21:11.088991Z","steps":["trace[94436763] 'process raft request' (duration: 122.0666ms)","trace[94436763] 'compare' (duration: 57.291523ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:21:11.089219Z","caller":"traceutil/trace.go:171","msg":"trace[69022755] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"178.865173ms","start":"2026-07-03T19:21:10.910339Z","end":"2026-07-03T19:21:11.089204Z","steps":["trace[69022755] 'process raft request' (duration: 178.748641ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:11.089301Z","caller":"traceutil/trace.go:171","msg":"trace[356932110] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"178.794073ms","start":"2026-07-03T19:21:10.910477Z","end":"2026-07-03T19:21:11.089271Z","steps":["trace[356932110] 'process raft request' (duration: 178.64338ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:11.089323Z","caller":"traceutil/trace.go:171","msg":"trace[1524672096] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"178.975026ms","start":"2026-07-03T19:21:10.91031Z","end":"2026-07-03T19:21:11.089285Z","steps":["trace[1524672096] 'process raft request' (duration: 178.503138ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:11.089163Z","caller":"traceutil/trace.go:171","msg":"trace[995085853] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1057; }","duration":"168.50752ms","start":"2026-07-03T19:21:10.920641Z","end":"2026-07-03T19:21:11.089148Z","steps":["trace[995085853] 'read index received' (duration: 110.800189ms)","trace[995085853] 'applied index is now lower than readState.Index' (duration: 57.706681ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:21:11.089569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.909946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:21:11.090089Z","caller":"traceutil/trace.go:171","msg":"trace[1168437580] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1021; }","duration":"169.465698ms","start":"2026-07-03T19:21:10.920607Z","end":"2026-07-03T19:21:11.090072Z","steps":["trace[1168437580] 'agreement among raft nodes before linearized reading' (duration: 168.895407ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:12.165994Z","caller":"traceutil/trace.go:171","msg":"trace[1381866620] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"150.22265ms","start":"2026-07-03T19:21:12.01573Z","end":"2026-07-03T19:21:12.165952Z","steps":["trace[1381866620] 'process raft request' (duration: 135.500491ms)","trace[1381866620] 'compare' (duration: 14.569476ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:21:15.361989Z","caller":"traceutil/trace.go:171","msg":"trace[1065771253] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"110.216201ms","start":"2026-07-03T19:21:15.251743Z","end":"2026-07-03T19:21:15.361959Z","steps":["trace[1065771253] 'process raft request' (duration: 106.059749ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:15.362116Z","caller":"traceutil/trace.go:171","msg":"trace[1381926176] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"109.04252ms","start":"2026-07-03T19:21:15.253049Z","end":"2026-07-03T19:21:15.362091Z","steps":["trace[1381926176] 'process raft request' (duration: 108.859337ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:17.398369Z","caller":"traceutil/trace.go:171","msg":"trace[121541741] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"105.68676ms","start":"2026-07-03T19:21:17.292662Z","end":"2026-07-03T19:21:17.398349Z","steps":["trace[121541741] 'process raft request' (duration: 105.467615ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:19.680426Z","caller":"traceutil/trace.go:171","msg":"trace[134103606] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"155.939023ms","start":"2026-07-03T19:21:19.524456Z","end":"2026-07-03T19:21:19.680395Z","steps":["trace[134103606] 'process raft request' (duration: 155.821431ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:19.680596Z","caller":"traceutil/trace.go:171","msg":"trace[1464101570] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"156.206849ms","start":"2026-07-03T19:21:19.52436Z","end":"2026-07-03T19:21:19.680567Z","steps":["trace[1464101570] 'process raft request' (duration: 134.515573ms)","trace[1464101570] 'compare' (duration: 21.193297ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:21:23.448246Z","caller":"traceutil/trace.go:171","msg":"trace[1709606325] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"106.108444ms","start":"2026-07-03T19:21:23.342113Z","end":"2026-07-03T19:21:23.448221Z","steps":["trace[1709606325] 'process raft request' (duration: 105.937291ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:25.209436Z","caller":"traceutil/trace.go:171","msg":"trace[479337331] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"158.140319ms","start":"2026-07-03T19:21:25.05126Z","end":"2026-07-03T19:21:25.209401Z","steps":["trace[479337331] 'read index received' (duration: 157.751903ms)","trace[479337331] 'applied index is now lower than readState.Index' (duration: 386.996µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:21:25.209524Z","caller":"traceutil/trace.go:171","msg":"trace[565693595] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"173.885514ms","start":"2026-07-03T19:21:25.035595Z","end":"2026-07-03T19:21:25.209481Z","steps":["trace[565693595] 'process raft request' (duration: 173.567459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:21:25.209788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.495764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:21:25.209917Z","caller":"traceutil/trace.go:171","msg":"trace[2131841956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1067; }","duration":"158.711718ms","start":"2026-07-03T19:21:25.051185Z","end":"2026-07-03T19:21:25.209897Z","steps":["trace[2131841956] 'agreement among raft nodes before linearized reading' (duration: 158.396783ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:27.936537Z","caller":"traceutil/trace.go:171","msg":"trace[229083679] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"205.175495ms","start":"2026-07-03T19:21:27.731346Z","end":"2026-07-03T19:21:27.936522Z","steps":["trace[229083679] 'read index received' (duration: 205.007483ms)","trace[229083679] 'applied index is now lower than readState.Index' (duration: 167.392µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:21:27.936726Z","caller":"traceutil/trace.go:171","msg":"trace[1096333709] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"306.015005ms","start":"2026-07-03T19:21:27.630686Z","end":"2026-07-03T19:21:27.936701Z","steps":["trace[1096333709] 'process raft request' (duration: 305.7124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:21:27.936832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.863761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-03T19:21:27.936875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:21:27.630654Z","time spent":"306.112106ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:21:27.936939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.173104ms","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-07-03T19:21:27.936959Z","caller":"traceutil/trace.go:171","msg":"trace[320808029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"132.233995ms","start":"2026-07-03T19:21:27.804721Z","end":"2026-07-03T19:21:27.936955Z","steps":["trace[320808029] 'agreement among raft nodes before linearized reading' (duration: 132.184003ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:27.936888Z","caller":"traceutil/trace.go:171","msg":"trace[695668344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1077; }","duration":"158.967163ms","start":"2026-07-03T19:21:27.777908Z","end":"2026-07-03T19:21:27.936875Z","steps":["trace[695668344] 'agreement among raft nodes before linearized reading' (duration: 158.79027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:21:27.936978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.571141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:21:27.93733Z","caller":"traceutil/trace.go:171","msg":"trace[919381109] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1077; }","duration":"206.0021ms","start":"2026-07-03T19:21:27.731303Z","end":"2026-07-03T19:21:27.937305Z","steps":["trace[919381109] 'agreement among raft nodes before linearized reading' (duration: 205.351858ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:30.5824Z","caller":"traceutil/trace.go:171","msg":"trace[535888352] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"274.443557ms","start":"2026-07-03T19:21:30.307943Z","end":"2026-07-03T19:21:30.582386Z","steps":["trace[535888352] 'process raft request' (duration: 274.267634ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:30.5824Z","caller":"traceutil/trace.go:171","msg":"trace[1691549739] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"264.618981ms","start":"2026-07-03T19:21:30.317755Z","end":"2026-07-03T19:21:30.582374Z","steps":["trace[1691549739] 'read index received' (duration: 264.370238ms)","trace[1691549739] 'applied index is now lower than readState.Index' (duration: 247.893µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:21:30.582554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.789624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-03T19:21:30.582584Z","caller":"traceutil/trace.go:171","msg":"trace[1055893716] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:1086; }","duration":"264.864986ms","start":"2026-07-03T19:21:30.317712Z","end":"2026-07-03T19:21:30.582577Z","steps":["trace[1055893716] 'agreement among raft nodes before linearized reading' (duration: 264.726193ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:31.677658Z","caller":"traceutil/trace.go:171","msg":"trace[714446765] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"110.556988ms","start":"2026-07-03T19:21:31.567047Z","end":"2026-07-03T19:21:31.677604Z","steps":["trace[714446765] 'process raft request' (duration: 110.405666ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:33.645132Z","caller":"traceutil/trace.go:171","msg":"trace[1915391334] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"256.936295ms","start":"2026-07-03T19:21:33.388172Z","end":"2026-07-03T19:21:33.645108Z","steps":["trace[1915391334] 'process raft request' (duration: 256.798053ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:35.632702Z","caller":"traceutil/trace.go:171","msg":"trace[264786435] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"196.21899ms","start":"2026-07-03T19:21:35.436467Z","end":"2026-07-03T19:21:35.632686Z","steps":["trace[264786435] 'read index received' (duration: 196.20214ms)","trace[264786435] 'applied index is now lower than readState.Index' (duration: 16.08µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:21:35.632793Z","caller":"traceutil/trace.go:171","msg":"trace[462341490] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"341.955712ms","start":"2026-07-03T19:21:35.290825Z","end":"2026-07-03T19:21:35.63278Z","steps":["trace[462341490] 'process raft request' (duration: 341.754109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:21:35.632825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.335111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:21:35.632854Z","caller":"traceutil/trace.go:171","msg":"trace[1387654740] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1100; }","duration":"196.432282ms","start":"2026-07-03T19:21:35.436413Z","end":"2026-07-03T19:21:35.632846Z","steps":["trace[1387654740] 'agreement among raft nodes before linearized reading' (duration: 196.337321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:21:35.632882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:21:35.290805Z","time spent":"342.030863ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:21:35.66194Z","caller":"traceutil/trace.go:171","msg":"trace[137182077] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"203.780376ms","start":"2026-07-03T19:21:35.45814Z","end":"2026-07-03T19:21:35.661921Z","steps":["trace[137182077] 'process raft request' (duration: 203.522863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:21:35.887291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.761121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:21:35.887443Z","caller":"traceutil/trace.go:171","msg":"trace[1294074563] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"180.091503ms","start":"2026-07-03T19:21:35.707339Z","end":"2026-07-03T19:21:35.88743Z","steps":["trace[1294074563] 'process raft request' (duration: 40.120508ms)","trace[1294074563] 'compare' (duration: 139.558637ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:21:36.304617Z","caller":"traceutil/trace.go:171","msg":"trace[1326781688] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"151.607853ms","start":"2026-07-03T19:21:36.152974Z","end":"2026-07-03T19:21:36.304581Z","steps":["trace[1326781688] 'process raft request' (duration: 79.235154ms)","trace[1326781688] 'compare' (duration: 72.251736ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:21:36.308563Z","caller":"traceutil/trace.go:171","msg":"trace[158441892] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"155.480491ms","start":"2026-07-03T19:21:36.153066Z","end":"2026-07-03T19:21:36.308546Z","steps":["trace[158441892] 'process raft request' (duration: 155.39487ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:52.529035Z","caller":"traceutil/trace.go:171","msg":"trace[1339527121] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"131.644962ms","start":"2026-07-03T19:21:52.397369Z","end":"2026-07-03T19:21:52.529014Z","steps":["trace[1339527121] 'process raft request' (duration: 131.52561ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:21:56.005713Z","caller":"traceutil/trace.go:171","msg":"trace[986958657] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1214; }","duration":"237.869209ms","start":"2026-07-03T19:21:55.767825Z","end":"2026-07-03T19:21:56.005695Z","steps":["trace[986958657] 'read index received' (duration: 171.875374ms)","trace[986958657] 'applied index is now lower than readState.Index' (duration: 65.992775ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:21:56.006337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.498718ms","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-07-03T19:21:56.006452Z","caller":"traceutil/trace.go:171","msg":"trace[73076173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1165; }","duration":"238.65191ms","start":"2026-07-03T19:21:55.767771Z","end":"2026-07-03T19:21:56.006423Z","steps":["trace[73076173] 'agreement among raft nodes before linearized reading' (duration: 238.013692ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:02.222958Z","caller":"traceutil/trace.go:171","msg":"trace[1733263762] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"113.305968ms","start":"2026-07-03T19:22:02.10962Z","end":"2026-07-03T19:22:02.222926Z","steps":["trace[1733263762] 'process raft request' (duration: 113.130416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:06.353249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.92434ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-03T19:22:06.353355Z","caller":"traceutil/trace.go:171","msg":"trace[1798360672] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1249; }","duration":"291.723946ms","start":"2026-07-03T19:22:06.061613Z","end":"2026-07-03T19:22:06.353337Z","steps":["trace[1798360672] 'read index received' (duration: 49.001µs)","trace[1798360672] 'applied index is now lower than readState.Index' (duration: 291.673175ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:22:06.353661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.03593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:22:06.35377Z","caller":"traceutil/trace.go:171","msg":"trace[553819171] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1198; }","duration":"292.189771ms","start":"2026-07-03T19:22:06.06156Z","end":"2026-07-03T19:22:06.35375Z","steps":["trace[553819171] 'agreement among raft nodes before linearized reading' (duration: 292.034999ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:06.397406Z","caller":"traceutil/trace.go:171","msg":"trace[1086080132] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"289.554947ms","start":"2026-07-03T19:22:06.107832Z","end":"2026-07-03T19:22:06.397387Z","steps":["trace[1086080132] 'process raft request' (duration: 289.424785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:06.399537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.316997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-03T19:22:06.399591Z","caller":"traceutil/trace.go:171","msg":"trace[1930525043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1200; }","duration":"134.379467ms","start":"2026-07-03T19:22:06.265198Z","end":"2026-07-03T19:22:06.399578Z","steps":["trace[1930525043] 'agreement among raft nodes before linearized reading' (duration: 134.226474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:06.399809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.58168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-03T19:22:06.399848Z","caller":"traceutil/trace.go:171","msg":"trace[221740724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1200; }","duration":"128.642611ms","start":"2026-07-03T19:22:06.271196Z","end":"2026-07-03T19:22:06.399839Z","steps":["trace[221740724] 'agreement among raft nodes before linearized reading' (duration: 128.475699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:06.400515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.151587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050256"} {"level":"info","ts":"2026-07-03T19:22:06.400578Z","caller":"traceutil/trace.go:171","msg":"trace[1182891909] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1200; }","duration":"320.245389ms","start":"2026-07-03T19:22:06.080318Z","end":"2026-07-03T19:22:06.400564Z","steps":["trace[1182891909] 'agreement among raft nodes before linearized reading' (duration: 319.388948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:06.400616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:06.080301Z","time spent":"320.306ms","remote":"127.0.0.1:46068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050279,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-07-03T19:22:10.806468Z","caller":"traceutil/trace.go:171","msg":"trace[1717707400] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1267; }","duration":"111.809484ms","start":"2026-07-03T19:22:10.69464Z","end":"2026-07-03T19:22:10.80645Z","steps":["trace[1717707400] 'read index received' (duration: 95.150203ms)","trace[1717707400] 'applied index is now lower than readState.Index' (duration: 16.658261ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:10.806499Z","caller":"traceutil/trace.go:171","msg":"trace[2101891345] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"336.345144ms","start":"2026-07-03T19:22:10.470119Z","end":"2026-07-03T19:22:10.806464Z","steps":["trace[2101891345] 'process raft request' (duration: 319.775414ms)","trace[2101891345] 'compare' (duration: 16.365617ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:10.806554Z","caller":"traceutil/trace.go:171","msg":"trace[1219313571] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"336.311384ms","start":"2026-07-03T19:22:10.47021Z","end":"2026-07-03T19:22:10.806521Z","steps":["trace[1219313571] 'process raft request' (duration: 336.194922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:10.806661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:10.470101Z","time spent":"336.481246ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:22:10.806687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.021996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-03T19:22:10.806723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:10.470201Z","time spent":"336.439375ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:22:10.806734Z","caller":"traceutil/trace.go:171","msg":"trace[1951168592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1216; }","duration":"112.130548ms","start":"2026-07-03T19:22:10.694589Z","end":"2026-07-03T19:22:10.806719Z","steps":["trace[1951168592] 'agreement among raft nodes before linearized reading' (duration: 111.981326ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:11.239581Z","caller":"traceutil/trace.go:171","msg":"trace[1172359578] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"148.278291ms","start":"2026-07-03T19:22:11.091274Z","end":"2026-07-03T19:22:11.239553Z","steps":["trace[1172359578] 'process raft request' (duration: 148.126199ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:12.947197Z","caller":"traceutil/trace.go:171","msg":"trace[720309982] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1273; }","duration":"125.322583ms","start":"2026-07-03T19:22:12.821852Z","end":"2026-07-03T19:22:12.947174Z","steps":["trace[720309982] 'read index received' (duration: 9.08803ms)","trace[720309982] 'applied index is now lower than readState.Index' (duration: 116.233573ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:12.947296Z","caller":"traceutil/trace.go:171","msg":"trace[721294450] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"132.01092ms","start":"2026-07-03T19:22:12.815274Z","end":"2026-07-03T19:22:12.947285Z","steps":["trace[721294450] 'process raft request' (duration: 124.950106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:12.947485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.618396ms","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-07-03T19:22:12.947511Z","caller":"traceutil/trace.go:171","msg":"trace[251669781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1222; }","duration":"125.698627ms","start":"2026-07-03T19:22:12.821806Z","end":"2026-07-03T19:22:12.947505Z","steps":["trace[251669781] 'agreement among raft nodes before linearized reading' (duration: 125.588935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:12.947585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.827672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T19:22:12.947637Z","caller":"traceutil/trace.go:171","msg":"trace[1784428350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1222; }","duration":"104.918582ms","start":"2026-07-03T19:22:12.842705Z","end":"2026-07-03T19:22:12.947623Z","steps":["trace[1784428350] 'agreement among raft nodes before linearized reading' (duration: 104.778901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:16.848329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.481527ms","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-07-03T19:22:16.848436Z","caller":"traceutil/trace.go:171","msg":"trace[1058222302] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1233; }","duration":"246.635579ms","start":"2026-07-03T19:22:16.601778Z","end":"2026-07-03T19:22:16.848414Z","steps":["trace[1058222302] 'range keys from in-memory index tree' (duration: 246.302345ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:17.112874Z","caller":"traceutil/trace.go:171","msg":"trace[1666973880] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"182.491649ms","start":"2026-07-03T19:22:16.930361Z","end":"2026-07-03T19:22:17.112853Z","steps":["trace[1666973880] 'read index received' (duration: 182.299627ms)","trace[1666973880] 'applied index is now lower than readState.Index' (duration: 191.102µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:17.112963Z","caller":"traceutil/trace.go:171","msg":"trace[1065577236] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"259.843056ms","start":"2026-07-03T19:22:16.853111Z","end":"2026-07-03T19:22:17.112954Z","steps":["trace[1065577236] 'process raft request' (duration: 259.612763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:17.113302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.244406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:22:17.11334Z","caller":"traceutil/trace.go:171","msg":"trace[546817074] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:0; response_revision:1234; }","duration":"129.314777ms","start":"2026-07-03T19:22:16.984015Z","end":"2026-07-03T19:22:17.11333Z","steps":["trace[546817074] 'agreement among raft nodes before linearized reading' (duration: 129.243766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:17.113332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.937594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T19:22:17.113437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.08917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-03T19:22:17.113449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.335323ms","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-07-03T19:22:17.113518Z","caller":"traceutil/trace.go:171","msg":"trace[1262499783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"134.224992ms","start":"2026-07-03T19:22:16.97927Z","end":"2026-07-03T19:22:17.113495Z","steps":["trace[1262499783] 'agreement among raft nodes before linearized reading' (duration: 133.953679ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:17.11357Z","caller":"traceutil/trace.go:171","msg":"trace[1135721016] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1234; }","duration":"183.165408ms","start":"2026-07-03T19:22:16.930311Z","end":"2026-07-03T19:22:17.113477Z","steps":["trace[1135721016] 'agreement among raft nodes before linearized reading' (duration: 182.908825ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:17.113589Z","caller":"traceutil/trace.go:171","msg":"trace[542291491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1234; }","duration":"134.505955ms","start":"2026-07-03T19:22:16.979049Z","end":"2026-07-03T19:22:17.113555Z","steps":["trace[542291491] 'agreement among raft nodes before linearized reading' (duration: 134.166181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:17.113615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.576597ms","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-07-03T19:22:17.113643Z","caller":"traceutil/trace.go:171","msg":"trace[144608658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"134.646807ms","start":"2026-07-03T19:22:16.978988Z","end":"2026-07-03T19:22:17.113635Z","steps":["trace[144608658] 'agreement among raft nodes before linearized reading' (duration: 134.538626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:17.113935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.384036ms","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-07-03T19:22:17.114135Z","caller":"traceutil/trace.go:171","msg":"trace[117400529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"179.225347ms","start":"2026-07-03T19:22:16.934873Z","end":"2026-07-03T19:22:17.114098Z","steps":["trace[117400529] 'agreement among raft nodes before linearized reading' (duration: 178.211424ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:17.266908Z","caller":"traceutil/trace.go:171","msg":"trace[1656056147] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"148.379075ms","start":"2026-07-03T19:22:17.118503Z","end":"2026-07-03T19:22:17.266882Z","steps":["trace[1656056147] 'process raft request' (duration: 56.161509ms)","trace[1656056147] 'compare' (duration: 91.444096ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:17.267053Z","caller":"traceutil/trace.go:171","msg":"trace[51469591] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"148.02972ms","start":"2026-07-03T19:22:17.119012Z","end":"2026-07-03T19:22:17.267041Z","steps":["trace[51469591] 'process raft request' (duration: 147.840577ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:17.267265Z","caller":"traceutil/trace.go:171","msg":"trace[32877385] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"148.158301ms","start":"2026-07-03T19:22:17.119092Z","end":"2026-07-03T19:22:17.267251Z","steps":["trace[32877385] 'process raft request' (duration: 147.912638ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:17.267518Z","caller":"traceutil/trace.go:171","msg":"trace[764574088] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1288; }","duration":"147.32226ms","start":"2026-07-03T19:22:17.120181Z","end":"2026-07-03T19:22:17.267504Z","steps":["trace[764574088] 'read index received' (duration: 54.569858ms)","trace[764574088] 'applied index is now lower than readState.Index' (duration: 92.751082ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:22:17.267613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.411721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/default/\" range_end:\"/registry/limitranges/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:22:17.267669Z","caller":"traceutil/trace.go:171","msg":"trace[646320789] range","detail":"{range_begin:/registry/limitranges/default/; range_end:/registry/limitranges/default0; response_count:0; response_revision:1238; }","duration":"147.481952ms","start":"2026-07-03T19:22:17.120177Z","end":"2026-07-03T19:22:17.267659Z","steps":["trace[646320789] 'agreement among raft nodes before linearized reading' (duration: 147.374481ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:17.267851Z","caller":"traceutil/trace.go:171","msg":"trace[1540112742] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"148.312183ms","start":"2026-07-03T19:22:17.1195Z","end":"2026-07-03T19:22:17.267812Z","steps":["trace[1540112742] 'process raft request' (duration: 147.705656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:17.67048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.641324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:22:17.670619Z","caller":"traceutil/trace.go:171","msg":"trace[293546399] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"399.119987ms","start":"2026-07-03T19:22:17.271476Z","end":"2026-07-03T19:22:17.670596Z","steps":["trace[293546399] 'process raft request' (duration: 292.268182ms)","trace[293546399] 'compare' (duration: 106.313769ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:22:17.670691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:17.271465Z","time spent":"399.188808ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:22:17.731083Z","caller":"traceutil/trace.go:171","msg":"trace[1556327545] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1292; }","duration":"426.773266ms","start":"2026-07-03T19:22:17.304282Z","end":"2026-07-03T19:22:17.731055Z","steps":["trace[1556327545] 'read index received' (duration: 259.489666ms)","trace[1556327545] 'applied index is now lower than readState.Index' (duration: 167.28199ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:17.731143Z","caller":"traceutil/trace.go:171","msg":"trace[1601914162] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"459.61817ms","start":"2026-07-03T19:22:17.271487Z","end":"2026-07-03T19:22:17.731105Z","steps":["trace[1601914162] 'process raft request' (duration: 459.429388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:17.731296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:17.271473Z","time spent":"459.762734ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:22:17.731524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.545238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:22:17.731593Z","caller":"traceutil/trace.go:171","msg":"trace[1626837292] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1240; }","duration":"142.660589ms","start":"2026-07-03T19:22:17.588916Z","end":"2026-07-03T19:22:17.731577Z","steps":["trace[1626837292] 'agreement among raft nodes before linearized reading' (duration: 142.524718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:17.731603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.299532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T19:22:17.731611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.106539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:22:17.731658Z","caller":"traceutil/trace.go:171","msg":"trace[749643659] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1240; }","duration":"427.397673ms","start":"2026-07-03T19:22:17.304242Z","end":"2026-07-03T19:22:17.73164Z","steps":["trace[749643659] 'agreement among raft nodes before linearized reading' (duration: 426.978168ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:17.731688Z","caller":"traceutil/trace.go:171","msg":"trace[1576743751] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1240; }","duration":"235.25487ms","start":"2026-07-03T19:22:17.496412Z","end":"2026-07-03T19:22:17.731666Z","steps":["trace[1576743751] 'agreement among raft nodes before linearized reading' (duration: 234.863316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:17.731704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:17.304232Z","time spent":"427.460534ms","remote":"127.0.0.1:48376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-07-03T19:22:18.843829Z","caller":"traceutil/trace.go:171","msg":"trace[720583952] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"104.500602ms","start":"2026-07-03T19:22:18.739308Z","end":"2026-07-03T19:22:18.843808Z","steps":["trace[720583952] 'read index received' (duration: 86.65583ms)","trace[720583952] 'applied index is now lower than readState.Index' (duration: 17.843742ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:18.844Z","caller":"traceutil/trace.go:171","msg":"trace[757828888] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"106.495777ms","start":"2026-07-03T19:22:18.737452Z","end":"2026-07-03T19:22:18.843948Z","steps":["trace[757828888] 'process raft request' (duration: 88.585614ms)","trace[757828888] 'compare' (duration: 17.584989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:22:18.844307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.736115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f92234fa-6d23-4ba8-b51f-b58fb5a8b77f\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-07-03T19:22:18.84438Z","caller":"traceutil/trace.go:171","msg":"trace[2128335762] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f92234fa-6d23-4ba8-b51f-b58fb5a8b77f; range_end:; response_count:1; response_revision:1248; }","duration":"105.14853ms","start":"2026-07-03T19:22:18.739205Z","end":"2026-07-03T19:22:18.844354Z","steps":["trace[2128335762] 'agreement among raft nodes before linearized reading' (duration: 104.691054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:19.411875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.813965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T19:22:19.411964Z","caller":"traceutil/trace.go:171","msg":"trace[991223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1249; }","duration":"140.947818ms","start":"2026-07-03T19:22:19.270997Z","end":"2026-07-03T19:22:19.411945Z","steps":["trace[991223] 'range keys from in-memory index tree' (duration: 140.498162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:19.411865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.716241ms","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-07-03T19:22:19.412112Z","caller":"traceutil/trace.go:171","msg":"trace[234531932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1249; }","duration":"139.962724ms","start":"2026-07-03T19:22:19.272077Z","end":"2026-07-03T19:22:19.41204Z","steps":["trace[234531932] 'range keys from in-memory index tree' (duration: 139.511957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:19.411949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.426197ms","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-07-03T19:22:19.412345Z","caller":"traceutil/trace.go:171","msg":"trace[452996515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1249; }","duration":"139.932703ms","start":"2026-07-03T19:22:19.272367Z","end":"2026-07-03T19:22:19.4123Z","steps":["trace[452996515] 'range keys from in-memory index tree' (duration: 139.130272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:19.41194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.880132ms","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-07-03T19:22:19.412601Z","caller":"traceutil/trace.go:171","msg":"trace[858821193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1249; }","duration":"140.838554ms","start":"2026-07-03T19:22:19.271735Z","end":"2026-07-03T19:22:19.412574Z","steps":["trace[858821193] 'range keys from in-memory index tree' (duration: 139.603548ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:19.821778Z","caller":"traceutil/trace.go:171","msg":"trace[1150007693] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"404.128518ms","start":"2026-07-03T19:22:19.417619Z","end":"2026-07-03T19:22:19.821747Z","steps":["trace[1150007693] 'process raft request' (duration: 404.085657ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:19.821787Z","caller":"traceutil/trace.go:171","msg":"trace[251188944] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"405.315134ms","start":"2026-07-03T19:22:19.416426Z","end":"2026-07-03T19:22:19.821741Z","steps":["trace[251188944] 'process raft request' (duration: 378.242964ms)","trace[251188944] 'compare' (duration: 26.835247ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:22:19.821972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:19.417605Z","time spent":"404.26122ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:22:19.821994Z","caller":"traceutil/trace.go:171","msg":"trace[930862859] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"405.214053ms","start":"2026-07-03T19:22:19.416767Z","end":"2026-07-03T19:22:19.821981Z","steps":["trace[930862859] 'process raft request' (duration: 404.905439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:19.822083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:19.416752Z","time spent":"405.296724ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:22:19.822082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:19.416411Z","time spent":"405.478007ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:22:19.82234Z","caller":"traceutil/trace.go:171","msg":"trace[364562632] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"138.992921ms","start":"2026-07-03T19:22:19.683317Z","end":"2026-07-03T19:22:19.82231Z","steps":["trace[364562632] 'process raft request' (duration: 138.825709ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:20.052811Z","caller":"traceutil/trace.go:171","msg":"trace[1276714640] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"147.6293ms","start":"2026-07-03T19:22:19.905145Z","end":"2026-07-03T19:22:20.052774Z","steps":["trace[1276714640] 'process raft request' (duration: 86.769098ms)","trace[1276714640] 'compare' (duration: 60.506637ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:21.395704Z","caller":"traceutil/trace.go:171","msg":"trace[89661804] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"243.48295ms","start":"2026-07-03T19:22:21.152186Z","end":"2026-07-03T19:22:21.395669Z","steps":["trace[89661804] 'process raft request' (duration: 243.205196ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:24.173168Z","caller":"traceutil/trace.go:171","msg":"trace[221985548] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"276.16501ms","start":"2026-07-03T19:22:23.896978Z","end":"2026-07-03T19:22:24.173143Z","steps":["trace[221985548] 'process raft request' (duration: 185.205185ms)","trace[221985548] 'compare' (duration: 90.701081ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:24.173432Z","caller":"traceutil/trace.go:171","msg":"trace[2088390672] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"276.278401ms","start":"2026-07-03T19:22:23.89713Z","end":"2026-07-03T19:22:24.173408Z","steps":["trace[2088390672] 'process raft request' (duration: 275.972647ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:24.173801Z","caller":"traceutil/trace.go:171","msg":"trace[1607548383] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"276.578535ms","start":"2026-07-03T19:22:23.89718Z","end":"2026-07-03T19:22:24.173759Z","steps":["trace[1607548383] 'process raft request' (duration: 276.15798ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:24.17391Z","caller":"traceutil/trace.go:171","msg":"trace[113485828] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1323; }","duration":"273.744149ms","start":"2026-07-03T19:22:23.900148Z","end":"2026-07-03T19:22:24.173892Z","steps":["trace[113485828] 'read index received' (duration: 10.743637ms)","trace[113485828] 'applied index is now lower than readState.Index' (duration: 262.998752ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:24.173984Z","caller":"traceutil/trace.go:171","msg":"trace[213091630] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"217.787266ms","start":"2026-07-03T19:22:23.956154Z","end":"2026-07-03T19:22:24.173942Z","steps":["trace[213091630] 'process raft request' (duration: 217.610134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:24.174141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.228051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:22:24.174191Z","caller":"traceutil/trace.go:171","msg":"trace[145807789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"140.335782ms","start":"2026-07-03T19:22:24.033842Z","end":"2026-07-03T19:22:24.174177Z","steps":["trace[145807789] 'agreement among raft nodes before linearized reading' (duration: 140.232921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:24.174198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.028883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f92234fa-6d23-4ba8-b51f-b58fb5a8b77f\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-07-03T19:22:24.174251Z","caller":"traceutil/trace.go:171","msg":"trace[1043933940] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f92234fa-6d23-4ba8-b51f-b58fb5a8b77f; range_end:; response_count:1; response_revision:1272; }","duration":"274.139574ms","start":"2026-07-03T19:22:23.900097Z","end":"2026-07-03T19:22:24.174237Z","steps":["trace[1043933940] 'agreement among raft nodes before linearized reading' (duration: 273.90118ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:25.70135Z","caller":"traceutil/trace.go:171","msg":"trace[230322693] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"116.522886ms","start":"2026-07-03T19:22:25.584802Z","end":"2026-07-03T19:22:25.701325Z","steps":["trace[230322693] 'process raft request' (duration: 110.386048ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:26.287563Z","caller":"traceutil/trace.go:171","msg":"trace[2038674069] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"104.921935ms","start":"2026-07-03T19:22:26.182618Z","end":"2026-07-03T19:22:26.287539Z","steps":["trace[2038674069] 'process raft request' (duration: 104.135125ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:26.287593Z","caller":"traceutil/trace.go:171","msg":"trace[168925607] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"104.926145ms","start":"2026-07-03T19:22:26.182644Z","end":"2026-07-03T19:22:26.28757Z","steps":["trace[168925607] 'process raft request' (duration: 104.816884ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:26.287554Z","caller":"traceutil/trace.go:171","msg":"trace[8451901] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1336; }","duration":"104.317887ms","start":"2026-07-03T19:22:26.183209Z","end":"2026-07-03T19:22:26.287527Z","steps":["trace[8451901] 'read index received' (duration: 2.598813ms)","trace[8451901] 'applied index is now lower than readState.Index' (duration: 101.717664ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:22:26.288012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.784723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f92234fa-6d23-4ba8-b51f-b58fb5a8b77f\" ","response":"range_response_count:1 size:3869"} {"level":"info","ts":"2026-07-03T19:22:26.288053Z","caller":"traceutil/trace.go:171","msg":"trace[360314738] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f92234fa-6d23-4ba8-b51f-b58fb5a8b77f; range_end:; response_count:1; response_revision:1282; }","duration":"104.857734ms","start":"2026-07-03T19:22:26.183184Z","end":"2026-07-03T19:22:26.288041Z","steps":["trace[360314738] 'agreement among raft nodes before linearized reading' (duration: 104.722232ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:27.429801Z","caller":"traceutil/trace.go:171","msg":"trace[1338547807] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"130.726306ms","start":"2026-07-03T19:22:27.29905Z","end":"2026-07-03T19:22:27.429776Z","steps":["trace[1338547807] 'process raft request' (duration: 130.563844ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:27.823985Z","caller":"traceutil/trace.go:171","msg":"trace[933456845] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1347; }","duration":"118.817265ms","start":"2026-07-03T19:22:27.705129Z","end":"2026-07-03T19:22:27.823946Z","steps":["trace[933456845] 'read index received' (duration: 57.062808ms)","trace[933456845] 'applied index is now lower than readState.Index' (duration: 61.752897ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:27.824186Z","caller":"traceutil/trace.go:171","msg":"trace[1803527807] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"177.408915ms","start":"2026-07-03T19:22:27.646749Z","end":"2026-07-03T19:22:27.824158Z","steps":["trace[1803527807] 'process raft request' (duration: 177.12819ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:27.824199Z","caller":"traceutil/trace.go:171","msg":"trace[1939247559] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"177.81133ms","start":"2026-07-03T19:22:27.64635Z","end":"2026-07-03T19:22:27.824161Z","steps":["trace[1939247559] 'process raft request' (duration: 115.833529ms)","trace[1939247559] 'compare' (duration: 61.503984ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:22:27.824254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.089909ms","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-07-03T19:22:27.824658Z","caller":"traceutil/trace.go:171","msg":"trace[683332490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"119.548536ms","start":"2026-07-03T19:22:27.705078Z","end":"2026-07-03T19:22:27.824626Z","steps":["trace[683332490] 'agreement among raft nodes before linearized reading' (duration: 119.002248ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:28.137476Z","caller":"traceutil/trace.go:171","msg":"trace[2078144521] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"137.666308ms","start":"2026-07-03T19:22:27.999786Z","end":"2026-07-03T19:22:28.137452Z","steps":["trace[2078144521] 'process raft request' (duration: 137.513166ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:28.599018Z","caller":"traceutil/trace.go:171","msg":"trace[87088470] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"293.334444ms","start":"2026-07-03T19:22:28.30566Z","end":"2026-07-03T19:22:28.598995Z","steps":["trace[87088470] 'process raft request' (duration: 202.066108ms)","trace[87088470] 'compare' (duration: 91.117944ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:28.599431Z","caller":"traceutil/trace.go:171","msg":"trace[710681773] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"293.001168ms","start":"2026-07-03T19:22:28.306202Z","end":"2026-07-03T19:22:28.599203Z","steps":["trace[710681773] 'process raft request' (duration: 292.736705ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:28.772544Z","caller":"traceutil/trace.go:171","msg":"trace[1779552028] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1355; }","duration":"153.937393ms","start":"2026-07-03T19:22:28.618579Z","end":"2026-07-03T19:22:28.772517Z","steps":["trace[1779552028] 'read index received' (duration: 104.516031ms)","trace[1779552028] 'applied index is now lower than readState.Index' (duration: 49.420032ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:22:28.772704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.099404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:22:28.772745Z","caller":"traceutil/trace.go:171","msg":"trace[783325539] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1299; }","duration":"154.195996ms","start":"2026-07-03T19:22:28.618537Z","end":"2026-07-03T19:22:28.772733Z","steps":["trace[783325539] 'agreement among raft nodes before linearized reading' (duration: 154.080615ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:28.77271Z","caller":"traceutil/trace.go:171","msg":"trace[91946424] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"174.329429ms","start":"2026-07-03T19:22:28.598317Z","end":"2026-07-03T19:22:28.772646Z","steps":["trace[91946424] 'process raft request' (duration: 124.858947ms)","trace[91946424] 'compare' (duration: 49.223249ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:29.072394Z","caller":"traceutil/trace.go:171","msg":"trace[1578653798] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1356; }","duration":"293.87867ms","start":"2026-07-03T19:22:28.778476Z","end":"2026-07-03T19:22:29.072354Z","steps":["trace[1578653798] 'read index received' (duration: 274.486297ms)","trace[1578653798] 'applied index is now lower than readState.Index' (duration: 19.390483ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:29.072428Z","caller":"traceutil/trace.go:171","msg":"trace[1236689858] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"294.582258ms","start":"2026-07-03T19:22:28.777786Z","end":"2026-07-03T19:22:29.072369Z","steps":["trace[1236689858] 'process raft request' (duration: 275.180545ms)","trace[1236689858] 'compare' (duration: 19.14726ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:22:29.07267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.192023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-07-03T19:22:29.072754Z","caller":"traceutil/trace.go:171","msg":"trace[1222646707] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1300; }","duration":"294.331784ms","start":"2026-07-03T19:22:28.778403Z","end":"2026-07-03T19:22:29.072734Z","steps":["trace[1222646707] 'agreement among raft nodes before linearized reading' (duration: 294.101991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:29.072869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.856943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-07-03T19:22:29.07297Z","caller":"traceutil/trace.go:171","msg":"trace[800361835] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1300; }","duration":"280.011235ms","start":"2026-07-03T19:22:28.792941Z","end":"2026-07-03T19:22:29.072952Z","steps":["trace[800361835] 'agreement among raft nodes before linearized reading' (duration: 279.686981ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:29.847855Z","caller":"traceutil/trace.go:171","msg":"trace[377070833] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"122.93957ms","start":"2026-07-03T19:22:29.724881Z","end":"2026-07-03T19:22:29.847821Z","steps":["trace[377070833] 'process raft request' (duration: 122.812408ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:30.011135Z","caller":"traceutil/trace.go:171","msg":"trace[84805334] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"153.219389ms","start":"2026-07-03T19:22:29.857891Z","end":"2026-07-03T19:22:30.011111Z","steps":["trace[84805334] 'process raft request' (duration: 153.167038ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:30.011134Z","caller":"traceutil/trace.go:171","msg":"trace[1803123323] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"158.854759ms","start":"2026-07-03T19:22:29.852249Z","end":"2026-07-03T19:22:30.011104Z","steps":["trace[1803123323] 'process raft request' (duration: 68.64729ms)","trace[1803123323] 'compare' (duration: 89.911845ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:30.011204Z","caller":"traceutil/trace.go:171","msg":"trace[995267149] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"158.471414ms","start":"2026-07-03T19:22:29.852707Z","end":"2026-07-03T19:22:30.011178Z","steps":["trace[995267149] 'process raft request' (duration: 158.298241ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:30.343854Z","caller":"traceutil/trace.go:171","msg":"trace[1006797857] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"201.523817ms","start":"2026-07-03T19:22:30.142276Z","end":"2026-07-03T19:22:30.3438Z","steps":["trace[1006797857] 'process raft request' (duration: 201.165673ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:30.454991Z","caller":"traceutil/trace.go:171","msg":"trace[1420988669] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"105.963693ms","start":"2026-07-03T19:22:30.349008Z","end":"2026-07-03T19:22:30.454972Z","steps":["trace[1420988669] 'process raft request' (duration: 105.845072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:32.777365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.133703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T19:22:32.77746Z","caller":"traceutil/trace.go:171","msg":"trace[2043354118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1315; }","duration":"157.262064ms","start":"2026-07-03T19:22:32.620186Z","end":"2026-07-03T19:22:32.777448Z","steps":["trace[2043354118] 'range keys from in-memory index tree' (duration: 156.89787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:32.777354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.863977ms","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-07-03T19:22:32.777678Z","caller":"traceutil/trace.go:171","msg":"trace[2102943951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1315; }","duration":"160.200401ms","start":"2026-07-03T19:22:32.617432Z","end":"2026-07-03T19:22:32.777632Z","steps":["trace[2102943951] 'range keys from in-memory index tree' (duration: 159.696675ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:32.966562Z","caller":"traceutil/trace.go:171","msg":"trace[1156246702] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"181.436216ms","start":"2026-07-03T19:22:32.7851Z","end":"2026-07-03T19:22:32.966537Z","steps":["trace[1156246702] 'process raft request' (duration: 181.311474ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:34.422626Z","caller":"traceutil/trace.go:171","msg":"trace[2004473729] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"368.841308ms","start":"2026-07-03T19:22:34.053758Z","end":"2026-07-03T19:22:34.4226Z","steps":["trace[2004473729] 'process raft request' (duration: 368.620225ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:34.422621Z","caller":"traceutil/trace.go:171","msg":"trace[1000503871] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1375; }","duration":"309.413152ms","start":"2026-07-03T19:22:34.11318Z","end":"2026-07-03T19:22:34.422593Z","steps":["trace[1000503871] 'read index received' (duration: 309.23958ms)","trace[1000503871] 'applied index is now lower than readState.Index' (duration: 172.012µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:22:34.422769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:34.053741Z","time spent":"368.95724ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:22:34.422932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.742176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-03T19:22:34.423059Z","caller":"traceutil/trace.go:171","msg":"trace[819160558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1318; }","duration":"309.912379ms","start":"2026-07-03T19:22:34.11313Z","end":"2026-07-03T19:22:34.423043Z","steps":["trace[819160558] 'agreement among raft nodes before linearized reading' (duration: 309.639786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:34.423135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:34.113117Z","time spent":"310.006611ms","remote":"127.0.0.1:46208","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-07-03T19:22:34.619883Z","caller":"traceutil/trace.go:171","msg":"trace[337645865] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"242.921654ms","start":"2026-07-03T19:22:34.376936Z","end":"2026-07-03T19:22:34.619857Z","steps":["trace[337645865] 'process raft request' (duration: 226.222297ms)","trace[337645865] 'compare' (duration: 16.469404ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:34.628329Z","caller":"traceutil/trace.go:171","msg":"trace[1510059395] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1376; }","duration":"146.194918ms","start":"2026-07-03T19:22:34.482114Z","end":"2026-07-03T19:22:34.628309Z","steps":["trace[1510059395] 'read index received' (duration: 121.039247ms)","trace[1510059395] 'applied index is now lower than readState.Index' (duration: 25.154401ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:34.628428Z","caller":"traceutil/trace.go:171","msg":"trace[1892621376] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"200.290326ms","start":"2026-07-03T19:22:34.428126Z","end":"2026-07-03T19:22:34.628417Z","steps":["trace[1892621376] 'process raft request' (duration: 200.064504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:34.628657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.124704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-03T19:22:34.628664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.520933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:22:34.628742Z","caller":"traceutil/trace.go:171","msg":"trace[1588467719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1320; }","duration":"119.227405ms","start":"2026-07-03T19:22:34.509471Z","end":"2026-07-03T19:22:34.628699Z","steps":["trace[1588467719] 'agreement among raft nodes before linearized reading' (duration: 119.065452ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:34.628742Z","caller":"traceutil/trace.go:171","msg":"trace[1146610237] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1320; }","duration":"146.662154ms","start":"2026-07-03T19:22:34.482062Z","end":"2026-07-03T19:22:34.628724Z","steps":["trace[1146610237] 'agreement among raft nodes before linearized reading' (duration: 146.488471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:44.620542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.812917ms","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-07-03T19:22:44.620664Z","caller":"traceutil/trace.go:171","msg":"trace[1160021309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1356; }","duration":"100.99505ms","start":"2026-07-03T19:22:44.519646Z","end":"2026-07-03T19:22:44.620641Z","steps":["trace[1160021309] 'range keys from in-memory index tree' (duration: 100.593135ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:45.09369Z","caller":"traceutil/trace.go:171","msg":"trace[1269970721] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"467.877597ms","start":"2026-07-03T19:22:44.625793Z","end":"2026-07-03T19:22:45.093671Z","steps":["trace[1269970721] 'process raft request' (duration: 467.742376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:45.093826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:44.625769Z","time spent":"467.980118ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:22:45.304131Z","caller":"traceutil/trace.go:171","msg":"trace[1269918121] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"603.453299ms","start":"2026-07-03T19:22:44.700649Z","end":"2026-07-03T19:22:45.304102Z","steps":["trace[1269918121] 'read index received' (duration: 393.021703ms)","trace[1269918121] 'applied index is now lower than readState.Index' (duration: 210.430246ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:45.304215Z","caller":"traceutil/trace.go:171","msg":"trace[1763862972] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"643.039457ms","start":"2026-07-03T19:22:44.661136Z","end":"2026-07-03T19:22:45.304175Z","steps":["trace[1763862972] 'process raft request' (duration: 624.264561ms)","trace[1763862972] 'compare' (duration: 18.489802ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:22:45.304312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.86568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:22:45.30435Z","caller":"traceutil/trace.go:171","msg":"trace[2075987271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1358; }","duration":"268.92218ms","start":"2026-07-03T19:22:45.035417Z","end":"2026-07-03T19:22:45.304339Z","steps":["trace[2075987271] 'agreement among raft nodes before linearized reading' (duration: 268.84116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:45.30437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.65406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-03T19:22:45.304354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.685101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-03T19:22:45.304385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:44.661111Z","time spent":"643.181938ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:22:45.304423Z","caller":"traceutil/trace.go:171","msg":"trace[827756975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1358; }","duration":"603.788163ms","start":"2026-07-03T19:22:44.700615Z","end":"2026-07-03T19:22:45.304404Z","steps":["trace[827756975] 'agreement among raft nodes before linearized reading' (duration: 603.570351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:45.304451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.248541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-03T19:22:45.304464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:44.700601Z","time spent":"603.854734ms","remote":"127.0.0.1:46208","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-07-03T19:22:45.304471Z","caller":"traceutil/trace.go:171","msg":"trace[551327493] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1358; }","duration":"393.304091ms","start":"2026-07-03T19:22:44.911161Z","end":"2026-07-03T19:22:45.304465Z","steps":["trace[551327493] 'agreement among raft nodes before linearized reading' (duration: 393.25446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:45.30451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:44.911138Z","time spent":"393.366171ms","remote":"127.0.0.1:46208","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-07-03T19:22:45.304401Z","caller":"traceutil/trace.go:171","msg":"trace[663277393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1358; }","duration":"602.72541ms","start":"2026-07-03T19:22:44.701667Z","end":"2026-07-03T19:22:45.304393Z","steps":["trace[663277393] 'agreement among raft nodes before linearized reading' (duration: 602.586559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:45.304575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:22:44.701648Z","time spent":"602.918854ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T19:22:45.304541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.146449ms","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-07-03T19:22:45.304663Z","caller":"traceutil/trace.go:171","msg":"trace[2118445168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1358; }","duration":"233.289912ms","start":"2026-07-03T19:22:45.071348Z","end":"2026-07-03T19:22:45.304638Z","steps":["trace[2118445168] 'agreement among raft nodes before linearized reading' (duration: 232.952717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:22:45.640549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.561811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:22:45.640737Z","caller":"traceutil/trace.go:171","msg":"trace[1485083546] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1362; }","duration":"134.805945ms","start":"2026-07-03T19:22:45.505914Z","end":"2026-07-03T19:22:45.64072Z","steps":["trace[1485083546] 'agreement among raft nodes before linearized reading' (duration: 134.526311ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:45.640572Z","caller":"traceutil/trace.go:171","msg":"trace[1139606559] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1422; }","duration":"134.46802ms","start":"2026-07-03T19:22:45.505967Z","end":"2026-07-03T19:22:45.640435Z","steps":["trace[1139606559] 'read index received' (duration: 89.531158ms)","trace[1139606559] 'applied index is now lower than readState.Index' (duration: 44.935322ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:51.675108Z","caller":"traceutil/trace.go:171","msg":"trace[1144584325] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"102.825093ms","start":"2026-07-03T19:22:51.572204Z","end":"2026-07-03T19:22:51.675029Z","steps":["trace[1144584325] 'process raft request' (duration: 78.677906ms)","trace[1144584325] 'compare' (duration: 24.013386ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:22:55.8051Z","caller":"traceutil/trace.go:171","msg":"trace[988383911] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"100.700049ms","start":"2026-07-03T19:22:55.70438Z","end":"2026-07-03T19:22:55.805081Z","steps":["trace[988383911] 'process raft request' (duration: 100.498177ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:55.952167Z","caller":"traceutil/trace.go:171","msg":"trace[1333096261] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"143.1677ms","start":"2026-07-03T19:22:55.808958Z","end":"2026-07-03T19:22:55.952125Z","steps":["trace[1333096261] 'process raft request' (duration: 85.844164ms)","trace[1333096261] 'compare' (duration: 57.070213ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:23:00.029836Z","caller":"traceutil/trace.go:171","msg":"trace[2143241037] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"162.875598ms","start":"2026-07-03T19:22:59.866898Z","end":"2026-07-03T19:23:00.029773Z","steps":["trace[2143241037] 'process raft request' (duration: 162.634726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:23:00.263418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.983906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:23:00.263616Z","caller":"traceutil/trace.go:171","msg":"trace[258461573] linearizableReadLoop","detail":"{readStateIndex:1477; appliedIndex:1476; }","duration":"224.85795ms","start":"2026-07-03T19:23:00.038737Z","end":"2026-07-03T19:23:00.263594Z","steps":["trace[258461573] 'read index received' (duration: 56.46886ms)","trace[258461573] 'applied index is now lower than readState.Index' (duration: 168.38723ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:23:00.263722Z","caller":"traceutil/trace.go:171","msg":"trace[1173887317] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"226.237316ms","start":"2026-07-03T19:23:00.037385Z","end":"2026-07-03T19:23:00.263623Z","steps":["trace[1173887317] 'process raft request' (duration: 57.924767ms)","trace[1173887317] 'compare' (duration: 167.476989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:23:00.263786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.019973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:1 size:655"} {"level":"info","ts":"2026-07-03T19:23:00.263869Z","caller":"traceutil/trace.go:171","msg":"trace[1591989374] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:1; response_revision:1414; }","duration":"225.118584ms","start":"2026-07-03T19:23:00.038733Z","end":"2026-07-03T19:23:00.263851Z","steps":["trace[1591989374] 'agreement among raft nodes before linearized reading' (duration: 224.966872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:23:00.263815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.040763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:namespace-controller\" ","response":"range_response_count:1 size:755"} {"level":"info","ts":"2026-07-03T19:23:00.263974Z","caller":"traceutil/trace.go:171","msg":"trace[812832436] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:namespace-controller; range_end:; response_count:1; response_revision:1414; }","duration":"131.229576ms","start":"2026-07-03T19:23:00.13273Z","end":"2026-07-03T19:23:00.26396Z","steps":["trace[812832436] 'agreement among raft nodes before linearized reading' (duration: 131.003133ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:23:10.453319Z","caller":"traceutil/trace.go:171","msg":"trace[2031999167] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"104.480662ms","start":"2026-07-03T19:23:10.348811Z","end":"2026-07-03T19:23:10.453292Z","steps":["trace[2031999167] 'process raft request' (duration: 104.34228ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:23:16.352393Z","caller":"traceutil/trace.go:171","msg":"trace[402228898] linearizableReadLoop","detail":"{readStateIndex:1532; appliedIndex:1531; }","duration":"132.974707ms","start":"2026-07-03T19:23:16.219386Z","end":"2026-07-03T19:23:16.352361Z","steps":["trace[402228898] 'read index received' (duration: 132.770735ms)","trace[402228898] 'applied index is now lower than readState.Index' (duration: 202.512µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:23:16.352566Z","caller":"traceutil/trace.go:171","msg":"trace[2108569954] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"142.343404ms","start":"2026-07-03T19:23:16.210123Z","end":"2026-07-03T19:23:16.352466Z","steps":["trace[2108569954] 'process raft request' (duration: 142.01627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:23:16.35264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.18116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-03T19:23:16.352731Z","caller":"traceutil/trace.go:171","msg":"trace[1460591020] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1465; }","duration":"133.368301ms","start":"2026-07-03T19:23:16.219344Z","end":"2026-07-03T19:23:16.352712Z","steps":["trace[1460591020] 'agreement among raft nodes before linearized reading' (duration: 133.136499ms)"],"step_count":1}