{"level":"warn","ts":"2026-06-18T16:06:24.576612Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-18T16:06:24.576781Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.83:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.83:2380","--initial-cluster=instance=https://199.19.213.83:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.83:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.83: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-06-18T16:06:24.576928Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-18T16:06:24.576972Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.83:2380"]} {"level":"info","ts":"2026-06-18T16:06:24.577018Z","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-06-18T16:06:24.578589Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"]} {"level":"info","ts":"2026-06-18T16:06:24.57874Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.83:2380"],"listen-peer-urls":["https://199.19.213.83:2380"],"advertise-client-urls":["https://199.19.213.83:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.83: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-06-18T16:06:24.58127Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.090681ms"} {"level":"info","ts":"2026-06-18T16:06:24.591659Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5887d25060a34f1b","cluster-id":"8c4d9ec591b76d84"} {"level":"info","ts":"2026-06-18T16:06:24.592482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=()"} {"level":"info","ts":"2026-06-18T16:06:24.592574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became follower at term 0"} {"level":"info","ts":"2026-06-18T16:06:24.592615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5887d25060a34f1b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-18T16:06:24.592727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became follower at term 1"} {"level":"info","ts":"2026-06-18T16:06:24.592901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=(6379298639854128923)"} {"level":"warn","ts":"2026-06-18T16:06:24.604017Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-18T16:06:24.605773Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-18T16:06:24.613259Z","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-06-18T16:06:24.617247Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5887d25060a34f1b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-18T16:06:24.617741Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5887d25060a34f1b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-18T16:06:24.617827Z","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-06-18T16:06:24.617875Z","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-06-18T16:06:24.617884Z","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-06-18T16:06:24.622813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=(6379298639854128923)"} {"level":"info","ts":"2026-06-18T16:06:24.623052Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8c4d9ec591b76d84","local-member-id":"5887d25060a34f1b","added-peer-id":"5887d25060a34f1b","added-peer-peer-urls":["https://199.19.213.83:2380"]} {"level":"info","ts":"2026-06-18T16:06:24.629184Z","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-06-18T16:06:24.629613Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.83:2380"} {"level":"info","ts":"2026-06-18T16:06:24.62965Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.83:2380"} {"level":"info","ts":"2026-06-18T16:06:24.629838Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5887d25060a34f1b","initial-advertise-peer-urls":["https://199.19.213.83:2380"],"listen-peer-urls":["https://199.19.213.83:2380"],"advertise-client-urls":["https://199.19.213.83:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-18T16:06:24.629886Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-18T16:06:25.493225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b is starting a new election at term 1"} {"level":"info","ts":"2026-06-18T16:06:25.493299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became pre-candidate at term 1"} {"level":"info","ts":"2026-06-18T16:06:25.493337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b received MsgPreVoteResp from 5887d25060a34f1b at term 1"} {"level":"info","ts":"2026-06-18T16:06:25.493356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became candidate at term 2"} {"level":"info","ts":"2026-06-18T16:06:25.493369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b received MsgVoteResp from 5887d25060a34f1b at term 2"} {"level":"info","ts":"2026-06-18T16:06:25.493392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became leader at term 2"} {"level":"info","ts":"2026-06-18T16:06:25.493407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5887d25060a34f1b elected leader 5887d25060a34f1b at term 2"} {"level":"info","ts":"2026-06-18T16:06:25.496211Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5887d25060a34f1b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.83:2379]}","request-path":"/0/members/5887d25060a34f1b/attributes","cluster-id":"8c4d9ec591b76d84","publish-timeout":"7s"} {"level":"info","ts":"2026-06-18T16:06:25.496263Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T16:06:25.496384Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T16:06:25.496428Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T16:06:25.499423Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-18T16:06:25.499849Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.83:2379"} {"level":"info","ts":"2026-06-18T16:06:25.501962Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-18T16:06:25.502023Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-18T16:06:25.513995Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8c4d9ec591b76d84","local-member-id":"5887d25060a34f1b","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T16:06:25.515507Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T16:06:25.515586Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T16:06:28.551325Z","caller":"traceutil/trace.go:171","msg":"trace[254299621] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"104.697396ms","start":"2026-06-18T16:06:28.446591Z","end":"2026-06-18T16:06:28.551288Z","steps":["trace[254299621] 'process raft request' (duration: 102.655245ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:06:38.718003Z","caller":"traceutil/trace.go:171","msg":"trace[394826246] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"248.388916ms","start":"2026-06-18T16:06:38.469588Z","end":"2026-06-18T16:06:38.717977Z","steps":["trace[394826246] 'process raft request' (duration: 248.242733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:06:44.884749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.895403ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-18T16:06:44.884856Z","caller":"traceutil/trace.go:171","msg":"trace[647026124] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:394; }","duration":"145.864665ms","start":"2026-06-18T16:06:44.738977Z","end":"2026-06-18T16:06:44.884842Z","steps":["trace[647026124] 'read index received' (duration: 37.181µs)","trace[647026124] 'applied index is now lower than readState.Index' (duration: 145.826234ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:06:44.88498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.46879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T16:06:44.885008Z","caller":"traceutil/trace.go:171","msg":"trace[49570602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:379; }","duration":"144.508381ms","start":"2026-06-18T16:06:44.740492Z","end":"2026-06-18T16:06:44.885Z","steps":["trace[49570602] 'agreement among raft nodes before linearized reading' (duration: 144.431319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:06:44.885077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.07633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:06:44.885177Z","caller":"traceutil/trace.go:171","msg":"trace[725224528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:379; }","duration":"146.224483ms","start":"2026-06-18T16:06:44.738932Z","end":"2026-06-18T16:06:44.885156Z","steps":["trace[725224528] 'agreement among raft nodes before linearized reading' (duration: 145.962757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:06:45.427577Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700324418879640695,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-18T16:06:45.673091Z","caller":"traceutil/trace.go:171","msg":"trace[1302632961] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:395; }","duration":"745.895789ms","start":"2026-06-18T16:06:44.927172Z","end":"2026-06-18T16:06:45.673068Z","steps":["trace[1302632961] 'read index received' (duration: 745.666613ms)","trace[1302632961] 'applied index is now lower than readState.Index' (duration: 228.246µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:06:45.673128Z","caller":"traceutil/trace.go:171","msg":"trace[226884149] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"784.530444ms","start":"2026-06-18T16:06:44.88857Z","end":"2026-06-18T16:06:45.6731Z","steps":["trace[226884149] 'process raft request' (duration: 784.353319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:06:45.673454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.243357ms","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-06-18T16:06:45.673545Z","caller":"traceutil/trace.go:171","msg":"trace[1119734056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:380; }","duration":"746.405811ms","start":"2026-06-18T16:06:44.927119Z","end":"2026-06-18T16:06:45.673525Z","steps":["trace[1119734056] 'agreement among raft nodes before linearized reading' (duration: 746.033222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:06:45.67358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:06:44.888551Z","time spent":"784.638037ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:06:45.673618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:06:44.927099Z","time spent":"746.509764ms","remote":"127.0.0.1:34568","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-06-18T16:06:45.673712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.292568ms","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-06-18T16:06:45.673763Z","caller":"traceutil/trace.go:171","msg":"trace[241444914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:380; }","duration":"746.397561ms","start":"2026-06-18T16:06:44.927349Z","end":"2026-06-18T16:06:45.673747Z","steps":["trace[241444914] 'agreement among raft nodes before linearized reading' (duration: 745.846077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:06:45.673801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:06:44.927328Z","time spent":"746.465553ms","remote":"127.0.0.1:34568","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-06-18T16:06:45.673817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.654539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-18T16:06:45.673855Z","caller":"traceutil/trace.go:171","msg":"trace[600122944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:380; }","duration":"526.726721ms","start":"2026-06-18T16:06:45.147116Z","end":"2026-06-18T16:06:45.673843Z","steps":["trace[600122944] 'agreement among raft nodes before linearized reading' (duration: 526.140967ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:06:45.673881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:06:45.147097Z","time spent":"526.778403ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-18T16:06:49.434476Z","caller":"traceutil/trace.go:171","msg":"trace[2014494383] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"247.71219ms","start":"2026-06-18T16:06:49.186733Z","end":"2026-06-18T16:06:49.434446Z","steps":["trace[2014494383] 'read index received' (duration: 147.958778ms)","trace[2014494383] 'applied index is now lower than readState.Index' (duration: 99.751981ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:06:49.434648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.651697ms","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-06-18T16:06:49.434639Z","caller":"traceutil/trace.go:171","msg":"trace[101259767] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"250.325674ms","start":"2026-06-18T16:06:49.184274Z","end":"2026-06-18T16:06:49.4346Z","steps":["trace[101259767] 'process raft request' (duration: 150.405868ms)","trace[101259767] 'compare' (duration: 99.634899ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:06:49.434695Z","caller":"traceutil/trace.go:171","msg":"trace[1524355104] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:402; }","duration":"249.74436ms","start":"2026-06-18T16:06:49.184937Z","end":"2026-06-18T16:06:49.434681Z","steps":["trace[1524355104] 'agreement among raft nodes before linearized reading' (duration: 249.613247ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:06:49.473136Z","caller":"traceutil/trace.go:171","msg":"trace[1574120340] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"282.638593ms","start":"2026-06-18T16:06:49.19047Z","end":"2026-06-18T16:06:49.473109Z","steps":["trace[1574120340] 'process raft request' (duration: 282.487759ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:06:49.630127Z","caller":"traceutil/trace.go:171","msg":"trace[932741747] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"121.860194ms","start":"2026-06-18T16:06:49.508238Z","end":"2026-06-18T16:06:49.630098Z","steps":["trace[932741747] 'process raft request' (duration: 37.696941ms)","trace[932741747] 'compare' (duration: 83.674351ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:06:49.630127Z","caller":"traceutil/trace.go:171","msg":"trace[1239252647] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"115.911316ms","start":"2026-06-18T16:06:49.514203Z","end":"2026-06-18T16:06:49.630114Z","steps":["trace[1239252647] 'process raft request' (duration: 115.875345ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:06:49.630369Z","caller":"traceutil/trace.go:171","msg":"trace[987999050] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"119.67537ms","start":"2026-06-18T16:06:49.510658Z","end":"2026-06-18T16:06:49.630333Z","steps":["trace[987999050] 'process raft request' (duration: 119.353432ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:06:49.985952Z","caller":"traceutil/trace.go:171","msg":"trace[677204109] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"351.000221ms","start":"2026-06-18T16:06:49.634913Z","end":"2026-06-18T16:06:49.985913Z","steps":["trace[677204109] 'process raft request' (duration: 321.695899ms)","trace[677204109] 'compare' (duration: 29.084066ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:06:49.98601Z","caller":"traceutil/trace.go:171","msg":"trace[1334959770] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:426; }","duration":"287.989006ms","start":"2026-06-18T16:06:49.697996Z","end":"2026-06-18T16:06:49.985984Z","steps":["trace[1334959770] 'read index received' (duration: 258.712535ms)","trace[1334959770] 'applied index is now lower than readState.Index' (duration: 29.275321ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:06:49.98609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:06:49.634893Z","time spent":"351.134603ms","remote":"127.0.0.1:34362","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-06-18T16:06:49.986297Z","caller":"traceutil/trace.go:171","msg":"trace[1799290689] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"349.665837ms","start":"2026-06-18T16:06:49.636597Z","end":"2026-06-18T16:06:49.986263Z","steps":["trace[1799290689] 'process raft request' (duration: 349.292018ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:06:49.986472Z","caller":"traceutil/trace.go:171","msg":"trace[576866047] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"350.972809ms","start":"2026-06-18T16:06:49.635456Z","end":"2026-06-18T16:06:49.986429Z","steps":["trace[576866047] 'process raft request' (duration: 350.383655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:06:49.986499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:06:49.636574Z","time spent":"349.828232ms","remote":"127.0.0.1:34770","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-06-18T16:06:49.986548Z","caller":"traceutil/trace.go:171","msg":"trace[874660467] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"348.392105ms","start":"2026-06-18T16:06:49.638135Z","end":"2026-06-18T16:06:49.986527Z","steps":["trace[874660467] 'process raft request' (duration: 347.80725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:06:49.986662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:06:49.635448Z","time spent":"351.080193ms","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T16:06:49.986716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.949391ms","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-06-18T16:06:49.986765Z","caller":"traceutil/trace.go:171","msg":"trace[420449987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"263.026012ms","start":"2026-06-18T16:06:49.723723Z","end":"2026-06-18T16:06:49.986749Z","steps":["trace[420449987] 'agreement among raft nodes before linearized reading' (duration: 262.93469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:06:49.986764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:06:49.63812Z","time spent":"348.480208ms","remote":"127.0.0.1:34744","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-06-18T16:06:49.986787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.632828ms","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-06-18T16:06:49.986467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.447017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T16:06:49.986837Z","caller":"traceutil/trace.go:171","msg":"trace[2017880545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"209.72315ms","start":"2026-06-18T16:06:49.777106Z","end":"2026-06-18T16:06:49.986829Z","steps":["trace[2017880545] 'agreement among raft nodes before linearized reading' (duration: 209.642528ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:06:49.986864Z","caller":"traceutil/trace.go:171","msg":"trace[2130380886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"288.900729ms","start":"2026-06-18T16:06:49.697947Z","end":"2026-06-18T16:06:49.986848Z","steps":["trace[2130380886] 'agreement among raft nodes before linearized reading' (duration: 288.406286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:06:49.986983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.393161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:06:49.987007Z","caller":"traceutil/trace.go:171","msg":"trace[1105382683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"245.450852ms","start":"2026-06-18T16:06:49.74155Z","end":"2026-06-18T16:06:49.987001Z","steps":["trace[1105382683] 'agreement among raft nodes before linearized reading' (duration: 245.413801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:06:49.987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.807772ms","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-06-18T16:06:49.987038Z","caller":"traceutil/trace.go:171","msg":"trace[897604617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:412; }","duration":"209.887954ms","start":"2026-06-18T16:06:49.777139Z","end":"2026-06-18T16:06:49.987027Z","steps":["trace[897604617] 'agreement among raft nodes before linearized reading' (duration: 209.804792ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:06:51.158402Z","caller":"traceutil/trace.go:171","msg":"trace[234100278] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"245.488145ms","start":"2026-06-18T16:06:50.912891Z","end":"2026-06-18T16:06:51.158379Z","steps":["trace[234100278] 'process raft request' (duration: 245.326381ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:07:04.175376Z","caller":"traceutil/trace.go:171","msg":"trace[1554419123] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"103.140157ms","start":"2026-06-18T16:07:04.072203Z","end":"2026-06-18T16:07:04.175344Z","steps":["trace[1554419123] 'process raft request' (duration: 102.760628ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:05.240427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700324418879640918,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T16:07:05.625686Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.127760041s","expected-duration":"1s"} {"level":"info","ts":"2026-06-18T16:07:05.626104Z","caller":"traceutil/trace.go:171","msg":"trace[43344297] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.128257253s","start":"2026-06-18T16:07:04.497818Z","end":"2026-06-18T16:07:05.626075Z","steps":["trace[43344297] 'process raft request' (duration: 1.128112599s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:05.626376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:04.497797Z","time spent":"1.128429007s","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T16:07:05.924788Z","caller":"traceutil/trace.go:171","msg":"trace[815266188] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.4201018s","start":"2026-06-18T16:07:04.504655Z","end":"2026-06-18T16:07:05.924756Z","steps":["trace[815266188] 'process raft request' (duration: 1.419918585s)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:07:05.924842Z","caller":"traceutil/trace.go:171","msg":"trace[928360820] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.416682234s","start":"2026-06-18T16:07:04.508132Z","end":"2026-06-18T16:07:05.924814Z","steps":["trace[928360820] 'process raft request' (duration: 1.41649s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:05.925033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:04.508114Z","time spent":"1.416826978s","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T16:07:05.925106Z","caller":"traceutil/trace.go:171","msg":"trace[1107070805] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.412468198s","start":"2026-06-18T16:07:04.512623Z","end":"2026-06-18T16:07:05.925091Z","steps":["trace[1107070805] 'process raft request' (duration: 1.412203422s)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:07:05.925141Z","caller":"traceutil/trace.go:171","msg":"trace[1016330683] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.4153171s","start":"2026-06-18T16:07:04.509771Z","end":"2026-06-18T16:07:05.925088Z","steps":["trace[1016330683] 'process raft request' (duration: 1.415007002s)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:07:05.925182Z","caller":"traceutil/trace.go:171","msg":"trace[1809446649] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.405797572s","start":"2026-06-18T16:07:04.519335Z","end":"2026-06-18T16:07:05.925132Z","steps":["trace[1809446649] 'process raft request' (duration: 1.405537096s)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:07:05.925253Z","caller":"traceutil/trace.go:171","msg":"trace[1673529702] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.379931896s","start":"2026-06-18T16:07:04.545308Z","end":"2026-06-18T16:07:05.92524Z","steps":["trace[1673529702] 'process raft request' (duration: 1.379778312s)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:07:05.925253Z","caller":"traceutil/trace.go:171","msg":"trace[110525026] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.415857965s","start":"2026-06-18T16:07:04.509371Z","end":"2026-06-18T16:07:05.925229Z","steps":["trace[110525026] 'process raft request' (duration: 1.415366892s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:05.925281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:04.519313Z","time spent":"1.405926016s","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T16:07:05.925306Z","caller":"traceutil/trace.go:171","msg":"trace[2018175049] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:511; }","duration":"1.184939383s","start":"2026-06-18T16:07:04.740348Z","end":"2026-06-18T16:07:05.925288Z","steps":["trace[2018175049] 'read index received' (duration: 885.955897ms)","trace[2018175049] 'applied index is now lower than readState.Index' (duration: 298.982496ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:07:05.925315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:04.509751Z","time spent":"1.415483294s","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T16:07:05.925038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:04.504637Z","time spent":"1.420337995s","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T16:07:05.925316Z","caller":"traceutil/trace.go:171","msg":"trace[1611331680] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.386637033s","start":"2026-06-18T16:07:04.53862Z","end":"2026-06-18T16:07:05.925257Z","steps":["trace[1611331680] 'process raft request' (duration: 1.386406618s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:05.925373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:04.509354Z","time spent":"1.415956327s","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T16:07:05.925319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:04.545295Z","time spent":"1.379994768s","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T16:07:05.925407Z","caller":"traceutil/trace.go:171","msg":"trace[1261219311] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.368527371s","start":"2026-06-18T16:07:04.55686Z","end":"2026-06-18T16:07:05.925387Z","steps":["trace[1261219311] 'process raft request' (duration: 1.368278995s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:05.925444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185080057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T16:07:05.925187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:04.51261Z","time spent":"1.412541601s","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T16:07:05.925487Z","caller":"traceutil/trace.go:171","msg":"trace[548873161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"1.185153169s","start":"2026-06-18T16:07:04.740321Z","end":"2026-06-18T16:07:05.925474Z","steps":["trace[548873161] 'agreement among raft nodes before linearized reading' (duration: 1.185043546s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:05.925486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:04.556837Z","time spent":"1.368610574s","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T16:07:05.925494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:04.538601Z","time spent":"1.386813448s","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T16:07:05.925517Z","caller":"traceutil/trace.go:171","msg":"trace[624636209] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.263796493s","start":"2026-06-18T16:07:04.661709Z","end":"2026-06-18T16:07:05.925505Z","steps":["trace[624636209] 'process raft request' (duration: 1.263514766s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:05.925526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:04.740305Z","time spent":"1.185212071s","remote":"127.0.0.1:34292","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-06-18T16:07:05.925567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:04.661677Z","time spent":"1.263866245s","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T16:07:05.925607Z","caller":"traceutil/trace.go:171","msg":"trace[1343878006] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.422356776s","start":"2026-06-18T16:07:04.502338Z","end":"2026-06-18T16:07:05.924695Z","steps":["trace[1343878006] 'process raft request' (duration: 1.42208995s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:05.925724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:04.502314Z","time spent":"1.423362951s","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T16:07:05.97736Z","caller":"traceutil/trace.go:171","msg":"trace[436835365] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"345.850657ms","start":"2026-06-18T16:07:05.631483Z","end":"2026-06-18T16:07:05.977334Z","steps":["trace[436835365] 'process raft request' (duration: 345.516979ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:07:05.977413Z","caller":"traceutil/trace.go:171","msg":"trace[1127508338] transaction","detail":"{read_only:false; number_of_response:1; response_revision:503; }","duration":"341.768146ms","start":"2026-06-18T16:07:05.635633Z","end":"2026-06-18T16:07:05.977402Z","steps":["trace[1127508338] 'process raft request' (duration: 341.549041ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:07:05.977414Z","caller":"traceutil/trace.go:171","msg":"trace[995045791] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"342.757871ms","start":"2026-06-18T16:07:05.634629Z","end":"2026-06-18T16:07:05.977387Z","steps":["trace[995045791] 'process raft request' (duration: 342.486374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:05.977472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:05.631459Z","time spent":"345.966901ms","remote":"127.0.0.1:34812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T16:07:05.977479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:05.635619Z","time spent":"341.815597ms","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:07:05.977567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:05.634604Z","time spent":"342.866624ms","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T16:07:12.101744Z","caller":"traceutil/trace.go:171","msg":"trace[1374648471] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"808.26711ms","start":"2026-06-18T16:07:11.293442Z","end":"2026-06-18T16:07:12.101709Z","steps":["trace[1374648471] 'process raft request' (duration: 803.642104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:12.101925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:11.293416Z","time spent":"808.410384ms","remote":"127.0.0.1:34380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:07:12.621691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.77333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T16:07:12.621893Z","caller":"traceutil/trace.go:171","msg":"trace[1027560704] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.024337234s","start":"2026-06-18T16:07:11.597519Z","end":"2026-06-18T16:07:12.621857Z","steps":["trace[1027560704] 'process raft request' (duration: 660.279816ms)","trace[1027560704] 'compare' (duration: 363.476653ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:07:12.622009Z","caller":"traceutil/trace.go:171","msg":"trace[1853318225] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"439.958296ms","start":"2026-06-18T16:07:12.182022Z","end":"2026-06-18T16:07:12.62198Z","steps":["trace[1853318225] 'process raft request' (duration: 439.804652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:12.622093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:12.181991Z","time spent":"440.069689ms","remote":"127.0.0.1:34654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T16:07:12.622116Z","caller":"traceutil/trace.go:171","msg":"trace[1718351149] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"881.815068ms","start":"2026-06-18T16:07:11.740194Z","end":"2026-06-18T16:07:12.62201Z","steps":["trace[1718351149] 'read index received' (duration: 355.518754ms)","trace[1718351149] 'applied index is now lower than readState.Index' (duration: 526.293454ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:07:12.622305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:11.597495Z","time spent":"1.024596579s","remote":"127.0.0.1:34568","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-06-18T16:07:12.622455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.23579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T16:07:12.62252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.926371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-18T16:07:12.622567Z","caller":"traceutil/trace.go:171","msg":"trace[1563683603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"485.011184ms","start":"2026-06-18T16:07:12.137542Z","end":"2026-06-18T16:07:12.622553Z","steps":["trace[1563683603] 'agreement among raft nodes before linearized reading' (duration: 484.799748ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:07:12.622571Z","caller":"traceutil/trace.go:171","msg":"trace[1393579753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"882.351742ms","start":"2026-06-18T16:07:11.740189Z","end":"2026-06-18T16:07:12.622541Z","steps":["trace[1393579753] 'agreement among raft nodes before linearized reading' (duration: 882.134826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:12.622605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:12.137525Z","time spent":"485.071265ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-18T16:07:12.62263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:11.740136Z","time spent":"882.480555ms","remote":"127.0.0.1:34292","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-06-18T16:07:12.622658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.221912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-18T16:07:12.622702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.043633ms","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-06-18T16:07:12.622698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.327894ms","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-06-18T16:07:12.622742Z","caller":"traceutil/trace.go:171","msg":"trace[1099173859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"386.342755ms","start":"2026-06-18T16:07:12.23637Z","end":"2026-06-18T16:07:12.622713Z","steps":["trace[1099173859] 'agreement among raft nodes before linearized reading' (duration: 385.977576ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:07:12.622766Z","caller":"traceutil/trace.go:171","msg":"trace[81071551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"386.420317ms","start":"2026-06-18T16:07:12.23633Z","end":"2026-06-18T16:07:12.62275Z","steps":["trace[81071551] 'agreement among raft nodes before linearized reading' (duration: 386.178251ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:07:12.622794Z","caller":"traceutil/trace.go:171","msg":"trace[528173590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"413.190156ms","start":"2026-06-18T16:07:12.209551Z","end":"2026-06-18T16:07:12.622741Z","steps":["trace[528173590] 'agreement among raft nodes before linearized reading' (duration: 412.964921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:12.622796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:12.23635Z","time spent":"386.433086ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T16:07:12.622805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:12.23627Z","time spent":"386.527209ms","remote":"127.0.0.1:34568","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-06-18T16:07:12.622831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:12.209523Z","time spent":"413.298589ms","remote":"127.0.0.1:34568","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-06-18T16:07:13.56078Z","caller":"traceutil/trace.go:171","msg":"trace[806369481] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"493.382062ms","start":"2026-06-18T16:07:13.067372Z","end":"2026-06-18T16:07:13.560755Z","steps":["trace[806369481] 'process raft request' (duration: 492.405578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:13.560943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:13.067345Z","time spent":"493.507856ms","remote":"127.0.0.1:34380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:07:14.140516Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700324418879641107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T16:07:14.641146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700324418879641107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T16:07:15.142073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700324418879641107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T16:07:15.199463Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.571555303s","expected-duration":"1s"} {"level":"info","ts":"2026-06-18T16:07:15.211183Z","caller":"traceutil/trace.go:171","msg":"trace[748758285] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.583365219s","start":"2026-06-18T16:07:13.627788Z","end":"2026-06-18T16:07:15.211154Z","steps":["trace[748758285] 'process raft request' (duration: 1.571930123s)","trace[748758285] 'compare' (duration: 11.209151ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:07:15.211323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:13.62776Z","time spent":"1.583492233s","remote":"127.0.0.1:34798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T16:07:15.368585Z","caller":"traceutil/trace.go:171","msg":"trace[960875825] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"1.728852958s","start":"2026-06-18T16:07:13.639704Z","end":"2026-06-18T16:07:15.368557Z","steps":["trace[960875825] 'read index received' (duration: 1.560020634s)","trace[960875825] 'applied index is now lower than readState.Index' (duration: 168.830503ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:07:15.368759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627434581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:07:15.368797Z","caller":"traceutil/trace.go:171","msg":"trace[349203100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"1.627527083s","start":"2026-06-18T16:07:13.74126Z","end":"2026-06-18T16:07:15.368787Z","steps":["trace[349203100] 'agreement among raft nodes before linearized reading' (duration: 1.627441591s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:15.368845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:13.741241Z","time spent":"1.627581924s","remote":"127.0.0.1:34284","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-06-18T16:07:15.369022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.729288158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v4crv\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-06-18T16:07:15.369116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.760276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:07:15.369094Z","caller":"traceutil/trace.go:171","msg":"trace[30497478] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v4crv; range_end:; response_count:1; response_revision:602; }","duration":"1.729426551s","start":"2026-06-18T16:07:13.639652Z","end":"2026-06-18T16:07:15.369078Z","steps":["trace[30497478] 'agreement among raft nodes before linearized reading' (duration: 1.72897027s)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:07:15.369154Z","caller":"traceutil/trace.go:171","msg":"trace[1741701294] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:602; }","duration":"145.844198ms","start":"2026-06-18T16:07:15.2233Z","end":"2026-06-18T16:07:15.369144Z","steps":["trace[1741701294] 'agreement among raft nodes before linearized reading' (duration: 145.793457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:15.369165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:13.639631Z","time spent":"1.729525954s","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-v4crv\" "} {"level":"warn","ts":"2026-06-18T16:07:15.369297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.669387ms","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-06-18T16:07:15.369344Z","caller":"traceutil/trace.go:171","msg":"trace[1103676831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"686.800121ms","start":"2026-06-18T16:07:14.682532Z","end":"2026-06-18T16:07:15.369332Z","steps":["trace[1103676831] 'agreement among raft nodes before linearized reading' (duration: 686.628266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:15.369372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:14.682508Z","time spent":"686.859712ms","remote":"127.0.0.1:34568","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-06-18T16:07:15.369355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.614291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-06-18T16:07:15.369411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493874049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:07:15.369451Z","caller":"traceutil/trace.go:171","msg":"trace[1820644649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"1.493943661s","start":"2026-06-18T16:07:13.875494Z","end":"2026-06-18T16:07:15.369438Z","steps":["trace[1820644649] 'agreement among raft nodes before linearized reading' (duration: 1.49389848s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:15.369487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:13.875476Z","time spent":"1.493999002s","remote":"127.0.0.1:34292","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-06-18T16:07:15.369414Z","caller":"traceutil/trace.go:171","msg":"trace[926911596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"685.727914ms","start":"2026-06-18T16:07:14.683672Z","end":"2026-06-18T16:07:15.3694Z","steps":["trace[926911596] 'agreement among raft nodes before linearized reading' (duration: 685.503469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:15.3696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.887372ms","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-06-18T16:07:15.369627Z","caller":"traceutil/trace.go:171","msg":"trace[779364420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"686.938044ms","start":"2026-06-18T16:07:14.682682Z","end":"2026-06-18T16:07:15.36962Z","steps":["trace[779364420] 'agreement among raft nodes before linearized reading' (duration: 686.871142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:15.369646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:14.682673Z","time spent":"686.968174ms","remote":"127.0.0.1:34568","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-06-18T16:07:15.369598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:14.683656Z","time spent":"685.932469ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-18T16:07:15.369373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.222196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-18T16:07:15.369718Z","caller":"traceutil/trace.go:171","msg":"trace[1884668340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"716.603726ms","start":"2026-06-18T16:07:14.653106Z","end":"2026-06-18T16:07:15.36971Z","steps":["trace[1884668340] 'agreement among raft nodes before linearized reading' (duration: 716.175635ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:15.369748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:14.653085Z","time spent":"716.653958ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T16:07:17.345914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.269199ms","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-06-18T16:07:17.345969Z","caller":"traceutil/trace.go:171","msg":"trace[424906422] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"550.007409ms","start":"2026-06-18T16:07:16.795938Z","end":"2026-06-18T16:07:17.345945Z","steps":["trace[424906422] 'process raft request' (duration: 546.873941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:17.346342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:16.795914Z","time spent":"550.379758ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T16:07:17.34629Z","caller":"traceutil/trace.go:171","msg":"trace[1966732961] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:625; }","duration":"154.657588ms","start":"2026-06-18T16:07:17.191605Z","end":"2026-06-18T16:07:17.346262Z","steps":["trace[1966732961] 'range keys from in-memory index tree' (duration: 154.239228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:31.11036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.434181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:07:31.110497Z","caller":"traceutil/trace.go:171","msg":"trace[359162246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"367.640347ms","start":"2026-06-18T16:07:30.742832Z","end":"2026-06-18T16:07:31.110472Z","steps":["trace[359162246] 'range keys from in-memory index tree' (duration: 367.343469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:31.110591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:30.742802Z","time spent":"367.733049ms","remote":"127.0.0.1:34292","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-06-18T16:07:31.170941Z","caller":"traceutil/trace.go:171","msg":"trace[1983331804] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"411.355651ms","start":"2026-06-18T16:07:30.75954Z","end":"2026-06-18T16:07:31.170896Z","steps":["trace[1983331804] 'process raft request' (duration: 411.074424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:07:31.171179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:07:30.759513Z","time spent":"411.555485ms","remote":"127.0.0.1:34532","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-06-18T16:08:06.26389Z","caller":"traceutil/trace.go:171","msg":"trace[920399598] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"567.62961ms","start":"2026-06-18T16:08:05.696239Z","end":"2026-06-18T16:08:06.263869Z","steps":["trace[920399598] 'process raft request' (duration: 490.196952ms)","trace[920399598] 'compare' (duration: 77.355456ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:08:06.264001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:05.696225Z","time spent":"567.722363ms","remote":"127.0.0.1:34568","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-06-18T16:08:07.031342Z","caller":"traceutil/trace.go:171","msg":"trace[676722018] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"1.333229091s","start":"2026-06-18T16:08:05.698076Z","end":"2026-06-18T16:08:07.031305Z","steps":["trace[676722018] 'process raft request' (duration: 1.33316301s)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:08:07.031372Z","caller":"traceutil/trace.go:171","msg":"trace[1562404053] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.334614887s","start":"2026-06-18T16:08:05.696718Z","end":"2026-06-18T16:08:07.031333Z","steps":["trace[1562404053] 'process raft request' (duration: 1.333256893s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:07.031453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:05.69805Z","time spent":"1.333348245s","remote":"127.0.0.1:34568","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-06-18T16:08:07.031527Z","caller":"traceutil/trace.go:171","msg":"trace[426807780] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1022; }","duration":"1.289477767s","start":"2026-06-18T16:08:05.742029Z","end":"2026-06-18T16:08:07.031507Z","steps":["trace[426807780] 'read index received' (duration: 444.430118ms)","trace[426807780] 'applied index is now lower than readState.Index' (duration: 845.046719ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:08:07.031556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:05.696696Z","time spent":"1.33475802s","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T16:08:07.031671Z","caller":"traceutil/trace.go:171","msg":"trace[328580435] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"1.333299793s","start":"2026-06-18T16:08:05.698363Z","end":"2026-06-18T16:08:07.031663Z","steps":["trace[328580435] 'process raft request' (duration: 1.332914664s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:07.031732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:05.698352Z","time spent":"1.333354134s","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T16:08:07.031791Z","caller":"traceutil/trace.go:171","msg":"trace[1211239262] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"630.42688ms","start":"2026-06-18T16:08:06.401357Z","end":"2026-06-18T16:08:07.031784Z","steps":["trace[1211239262] 'process raft request' (duration: 630.099162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:07.031796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289777934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:08:07.031821Z","caller":"traceutil/trace.go:171","msg":"trace[1640768129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"1.289843756s","start":"2026-06-18T16:08:05.741969Z","end":"2026-06-18T16:08:07.031813Z","steps":["trace[1640768129] 'agreement among raft nodes before linearized reading' (duration: 1.289806595s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:07.031839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:05.741943Z","time spent":"1.289891848s","remote":"127.0.0.1:34292","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-06-18T16:08:07.031853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:06.401331Z","time spent":"630.482152ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:08:07.031728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12092188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:08:07.031958Z","caller":"traceutil/trace.go:171","msg":"trace[967376294] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:984; }","duration":"1.121165076s","start":"2026-06-18T16:08:05.910783Z","end":"2026-06-18T16:08:07.031948Z","steps":["trace[967376294] 'agreement among raft nodes before linearized reading' (duration: 1.12089558s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:07.031988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:05.910718Z","time spent":"1.121259738s","remote":"127.0.0.1:34514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-18T16:08:07.032099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.951127ms","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-06-18T16:08:07.032121Z","caller":"traceutil/trace.go:171","msg":"trace[1062872248] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:984; }","duration":"544.003618ms","start":"2026-06-18T16:08:06.48811Z","end":"2026-06-18T16:08:07.032113Z","steps":["trace[1062872248] 'agreement among raft nodes before linearized reading' (duration: 543.949677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:07.03214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:06.488092Z","time spent":"544.04228ms","remote":"127.0.0.1:34654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-06-18T16:08:07.032179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042074498s","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-06-18T16:08:07.032199Z","caller":"traceutil/trace.go:171","msg":"trace[1429344896] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:984; }","duration":"1.04217684s","start":"2026-06-18T16:08:05.990016Z","end":"2026-06-18T16:08:07.032193Z","steps":["trace[1429344896] 'agreement among raft nodes before linearized reading' (duration: 1.04214106s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:07.03222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:05.989967Z","time spent":"1.042246982s","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-06-18T16:08:07.032239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.631285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T16:08:07.032284Z","caller":"traceutil/trace.go:171","msg":"trace[1218818199] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:984; }","duration":"588.713787ms","start":"2026-06-18T16:08:06.443555Z","end":"2026-06-18T16:08:07.032269Z","steps":["trace[1218818199] 'agreement among raft nodes before linearized reading' (duration: 588.630455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:07.032356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:06.443535Z","time spent":"588.80732ms","remote":"127.0.0.1:34638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-06-18T16:08:29.974568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.602657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:08:29.974659Z","caller":"traceutil/trace.go:171","msg":"trace[1768786762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"232.74087ms","start":"2026-06-18T16:08:29.741895Z","end":"2026-06-18T16:08:29.974636Z","steps":["trace[1768786762] 'range keys from in-memory index tree' (duration: 232.499114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:29.974646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.856819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-18T16:08:29.974723Z","caller":"traceutil/trace.go:171","msg":"trace[1807838308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1058; }","duration":"243.994992ms","start":"2026-06-18T16:08:29.730709Z","end":"2026-06-18T16:08:29.974704Z","steps":["trace[1807838308] 'range keys from in-memory index tree' (duration: 243.678814ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:08:30.465481Z","caller":"traceutil/trace.go:171","msg":"trace[802243425] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"485.669839ms","start":"2026-06-18T16:08:29.979778Z","end":"2026-06-18T16:08:30.465448Z","steps":["trace[802243425] 'process raft request' (duration: 485.374982ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:08:30.465556Z","caller":"traceutil/trace.go:171","msg":"trace[1728700191] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"100.282156ms","start":"2026-06-18T16:08:30.36504Z","end":"2026-06-18T16:08:30.465322Z","steps":["trace[1728700191] 'read index received' (duration: 100.100742ms)","trace[1728700191] 'applied index is now lower than readState.Index' (duration: 180.404µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:08:30.465661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.608035ms","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-06-18T16:08:30.465832Z","caller":"traceutil/trace.go:171","msg":"trace[271478608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1059; }","duration":"100.83163ms","start":"2026-06-18T16:08:30.364988Z","end":"2026-06-18T16:08:30.46582Z","steps":["trace[271478608] 'agreement among raft nodes before linearized reading' (duration: 100.528172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:30.465967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:29.979762Z","time spent":"485.898405ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T16:08:31.364462Z","caller":"traceutil/trace.go:171","msg":"trace[1263723863] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"152.711892ms","start":"2026-06-18T16:08:31.211721Z","end":"2026-06-18T16:08:31.364433Z","steps":["trace[1263723863] 'read index received' (duration: 152.498607ms)","trace[1263723863] 'applied index is now lower than readState.Index' (duration: 211.915µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:08:31.364595Z","caller":"traceutil/trace.go:171","msg":"trace[510429506] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"178.602312ms","start":"2026-06-18T16:08:31.185939Z","end":"2026-06-18T16:08:31.364541Z","steps":["trace[510429506] 'process raft request' (duration: 178.346386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:31.364715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.967898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-18T16:08:31.364769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.201019ms","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-06-18T16:08:31.364772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.765058ms","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-06-18T16:08:31.36479Z","caller":"traceutil/trace.go:171","msg":"trace[689717996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"153.090961ms","start":"2026-06-18T16:08:31.211678Z","end":"2026-06-18T16:08:31.364769Z","steps":["trace[689717996] 'agreement among raft nodes before linearized reading' (duration: 152.851516ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:08:31.364825Z","caller":"traceutil/trace.go:171","msg":"trace[20211552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1061; }","duration":"152.273771ms","start":"2026-06-18T16:08:31.212537Z","end":"2026-06-18T16:08:31.364811Z","steps":["trace[20211552] 'agreement among raft nodes before linearized reading' (duration: 152.072266ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:08:31.364831Z","caller":"traceutil/trace.go:171","msg":"trace[1042200262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"151.860601ms","start":"2026-06-18T16:08:31.212958Z","end":"2026-06-18T16:08:31.364819Z","steps":["trace[1042200262] 'agreement among raft nodes before linearized reading' (duration: 151.675366ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:31.793813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.036898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T16:08:31.793932Z","caller":"traceutil/trace.go:171","msg":"trace[2058393234] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"424.999046ms","start":"2026-06-18T16:08:31.368915Z","end":"2026-06-18T16:08:31.793915Z","steps":["trace[2058393234] 'process raft request' (duration: 244.62276ms)","trace[2058393234] 'compare' (duration: 179.895204ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:08:31.793972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:31.368887Z","time spent":"425.067408ms","remote":"127.0.0.1:34568","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-06-18T16:08:32.191816Z","caller":"traceutil/trace.go:171","msg":"trace[133588469] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1110; }","duration":"450.487454ms","start":"2026-06-18T16:08:31.741305Z","end":"2026-06-18T16:08:32.191792Z","steps":["trace[133588469] 'read index received' (duration: 426.778319ms)","trace[133588469] 'applied index is now lower than readState.Index' (duration: 23.707855ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:08:32.191934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.619958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:08:32.191967Z","caller":"traceutil/trace.go:171","msg":"trace[389155004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1064; }","duration":"450.70681ms","start":"2026-06-18T16:08:31.741249Z","end":"2026-06-18T16:08:32.191956Z","steps":["trace[389155004] 'agreement among raft nodes before linearized reading' (duration: 450.638488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:32.192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:31.741219Z","time spent":"450.773691ms","remote":"127.0.0.1:34292","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-06-18T16:08:32.191941Z","caller":"traceutil/trace.go:171","msg":"trace[837078602] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"822.525ms","start":"2026-06-18T16:08:31.369364Z","end":"2026-06-18T16:08:32.191889Z","steps":["trace[837078602] 'process raft request' (duration: 798.804136ms)","trace[837078602] 'compare' (duration: 23.401786ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:08:32.192132Z","caller":"traceutil/trace.go:171","msg":"trace[783215470] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"822.212922ms","start":"2026-06-18T16:08:31.3699Z","end":"2026-06-18T16:08:32.192113Z","steps":["trace[783215470] 'process raft request' (duration: 821.829592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:32.192207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:31.369343Z","time spent":"822.725625ms","remote":"127.0.0.1:34568","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-06-18T16:08:32.19228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:31.369889Z","time spent":"822.296655ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T16:08:32.662201Z","caller":"traceutil/trace.go:171","msg":"trace[1946711134] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"166.715923ms","start":"2026-06-18T16:08:32.495428Z","end":"2026-06-18T16:08:32.662144Z","steps":["trace[1946711134] 'read index received' (duration: 166.561659ms)","trace[1946711134] 'applied index is now lower than readState.Index' (duration: 153.164µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:08:32.662389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.939499ms","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-06-18T16:08:32.662426Z","caller":"traceutil/trace.go:171","msg":"trace[506051380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1065; }","duration":"166.99279ms","start":"2026-06-18T16:08:32.495424Z","end":"2026-06-18T16:08:32.662417Z","steps":["trace[506051380] 'agreement among raft nodes before linearized reading' (duration: 166.830756ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:08:32.662413Z","caller":"traceutil/trace.go:171","msg":"trace[1565382004] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"186.672764ms","start":"2026-06-18T16:08:32.475688Z","end":"2026-06-18T16:08:32.66236Z","steps":["trace[1565382004] 'process raft request' (duration: 186.350296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:32.879302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.792189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T16:08:32.879419Z","caller":"traceutil/trace.go:171","msg":"trace[1979482991] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1113; }","duration":"137.168283ms","start":"2026-06-18T16:08:32.742231Z","end":"2026-06-18T16:08:32.8794Z","steps":["trace[1979482991] 'read index received' (duration: 142.974µs)","trace[1979482991] 'applied index is now lower than readState.Index' (duration: 137.023868ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:08:32.879498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.275055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:08:32.879533Z","caller":"traceutil/trace.go:171","msg":"trace[395047390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"137.396538ms","start":"2026-06-18T16:08:32.742126Z","end":"2026-06-18T16:08:32.879522Z","steps":["trace[395047390] 'agreement among raft nodes before linearized reading' (duration: 137.339316ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:08:32.87944Z","caller":"traceutil/trace.go:171","msg":"trace[278532775] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"212.010791ms","start":"2026-06-18T16:08:32.667402Z","end":"2026-06-18T16:08:32.879413Z","steps":["trace[278532775] 'process raft request' (duration: 70.021498ms)","trace[278532775] 'compare' (duration: 141.641464ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:08:34.22338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.827076ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-18T16:08:34.53707Z","caller":"traceutil/trace.go:171","msg":"trace[983755102] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"180.442908ms","start":"2026-06-18T16:08:34.3566Z","end":"2026-06-18T16:08:34.537043Z","steps":["trace[983755102] 'process raft request' (duration: 180.306215ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:08:48.433147Z","caller":"traceutil/trace.go:171","msg":"trace[1099162568] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"116.408132ms","start":"2026-06-18T16:08:48.316704Z","end":"2026-06-18T16:08:48.433113Z","steps":["trace[1099162568] 'process raft request' (duration: 116.235897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:49.241087Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700324418879642398,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-18T16:08:49.54828Z","caller":"traceutil/trace.go:171","msg":"trace[457135148] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1220; }","duration":"807.484733ms","start":"2026-06-18T16:08:48.740769Z","end":"2026-06-18T16:08:49.548254Z","steps":["trace[457135148] 'read index received' (duration: 807.36699ms)","trace[457135148] 'applied index is now lower than readState.Index' (duration: 116.173µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:08:49.548324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:48.731096Z","time spent":"817.217027ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-18T16:08:49.548433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.646207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:08:49.548479Z","caller":"traceutil/trace.go:171","msg":"trace[666732704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1167; }","duration":"807.740729ms","start":"2026-06-18T16:08:48.740726Z","end":"2026-06-18T16:08:49.548466Z","steps":["trace[666732704] 'agreement among raft nodes before linearized reading' (duration: 807.637907ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:49.548518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:48.74071Z","time spent":"807.80014ms","remote":"127.0.0.1:34292","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-06-18T16:08:50.048805Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700324418879642402,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-18T16:08:50.385037Z","caller":"traceutil/trace.go:171","msg":"trace[527253912] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"903.672077ms","start":"2026-06-18T16:08:49.481338Z","end":"2026-06-18T16:08:50.38501Z","steps":["trace[527253912] 'process raft request' (duration: 902.054486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:50.3852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:49.481293Z","time spent":"903.838381ms","remote":"127.0.0.1:34568","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-06-18T16:08:50.38578Z","caller":"traceutil/trace.go:171","msg":"trace[929383534] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"836.144172ms","start":"2026-06-18T16:08:49.549622Z","end":"2026-06-18T16:08:50.385767Z","steps":["trace[929383534] 'process raft request' (duration: 835.800124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:50.385842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:49.549597Z","time spent":"836.214685ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T16:08:50.38565Z","caller":"traceutil/trace.go:171","msg":"trace[1579773304] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1221; }","duration":"837.280731ms","start":"2026-06-18T16:08:49.548341Z","end":"2026-06-18T16:08:50.385621Z","steps":["trace[1579773304] 'read index received' (duration: 835.209219ms)","trace[1579773304] 'applied index is now lower than readState.Index' (duration: 2.070162ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:08:50.38603Z","caller":"traceutil/trace.go:171","msg":"trace[831479250] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"694.152479ms","start":"2026-06-18T16:08:49.691868Z","end":"2026-06-18T16:08:50.386021Z","steps":["trace[831479250] 'process raft request' (duration: 693.706508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:50.386152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416426245s","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-06-18T16:08:50.386209Z","caller":"traceutil/trace.go:171","msg":"trace[2144654079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1170; }","duration":"1.416518096s","start":"2026-06-18T16:08:48.969683Z","end":"2026-06-18T16:08:50.386201Z","steps":["trace[2144654079] 'agreement among raft nodes before linearized reading' (duration: 1.416385393s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:50.386253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:48.969668Z","time spent":"1.416578118s","remote":"127.0.0.1:34568","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-06-18T16:08:50.386442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:49.691844Z","time spent":"694.338204ms","remote":"127.0.0.1:34568","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-06-18T16:08:50.386508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.619025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:08:50.386548Z","caller":"traceutil/trace.go:171","msg":"trace[470060345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1170; }","duration":"836.660426ms","start":"2026-06-18T16:08:49.549878Z","end":"2026-06-18T16:08:50.386538Z","steps":["trace[470060345] 'agreement among raft nodes before linearized reading' (duration: 836.600634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:50.386581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:49.549855Z","time spent":"836.718726ms","remote":"127.0.0.1:34284","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-06-18T16:08:50.386748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.490775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T16:08:50.38678Z","caller":"traceutil/trace.go:171","msg":"trace[216488083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1170; }","duration":"450.581607ms","start":"2026-06-18T16:08:49.93619Z","end":"2026-06-18T16:08:50.386772Z","steps":["trace[216488083] 'agreement among raft nodes before linearized reading' (duration: 450.500855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:50.386804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:49.936154Z","time spent":"450.644748ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T16:08:50.387572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.64663578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-18T16:08:50.387611Z","caller":"traceutil/trace.go:171","msg":"trace[505365445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1170; }","duration":"1.646685131s","start":"2026-06-18T16:08:48.740916Z","end":"2026-06-18T16:08:50.387601Z","steps":["trace[505365445] 'agreement among raft nodes before linearized reading' (duration: 1.646587118s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:08:50.38764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:08:48.740911Z","time spent":"1.646720482s","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-18T16:09:04.691363Z","caller":"traceutil/trace.go:171","msg":"trace[1156106726] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"182.006687ms","start":"2026-06-18T16:09:04.509325Z","end":"2026-06-18T16:09:04.691331Z","steps":["trace[1156106726] 'read index received' (duration: 181.835343ms)","trace[1156106726] 'applied index is now lower than readState.Index' (duration: 170.254µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:04.691401Z","caller":"traceutil/trace.go:171","msg":"trace[243445754] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"187.818403ms","start":"2026-06-18T16:09:04.50355Z","end":"2026-06-18T16:09:04.691368Z","steps":["trace[243445754] 'process raft request' (duration: 187.599977ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:04.692376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.760927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T16:09:04.692447Z","caller":"traceutil/trace.go:171","msg":"trace[165027541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1215; }","duration":"183.164827ms","start":"2026-06-18T16:09:04.509269Z","end":"2026-06-18T16:09:04.692434Z","steps":["trace[165027541] 'agreement among raft nodes before linearized reading' (duration: 182.212483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:04.771892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.907462ms","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-06-18T16:09:04.771969Z","caller":"traceutil/trace.go:171","msg":"trace[601273881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"262.041955ms","start":"2026-06-18T16:09:04.509906Z","end":"2026-06-18T16:09:04.771948Z","steps":["trace[601273881] 'agreement among raft nodes before linearized reading' (duration: 261.867771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:04.805722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.388891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-18T16:09:04.805816Z","caller":"traceutil/trace.go:171","msg":"trace[1955665445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1216; }","duration":"279.495493ms","start":"2026-06-18T16:09:04.526294Z","end":"2026-06-18T16:09:04.80579Z","steps":["trace[1955665445] 'agreement among raft nodes before linearized reading' (duration: 245.8777ms)","trace[1955665445] 'range keys from in-memory index tree' (duration: 33.396978ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:09:04.806326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.901539ms","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-06-18T16:09:04.806406Z","caller":"traceutil/trace.go:171","msg":"trace[1692968942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1216; }","duration":"281.021433ms","start":"2026-06-18T16:09:04.525365Z","end":"2026-06-18T16:09:04.806386Z","steps":["trace[1692968942] 'agreement among raft nodes before linearized reading' (duration: 246.839255ms)","trace[1692968942] 'range keys from in-memory index tree' (duration: 33.988142ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:09:04.806664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.773173ms","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-06-18T16:09:04.806721Z","caller":"traceutil/trace.go:171","msg":"trace[363962521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1216; }","duration":"295.842204ms","start":"2026-06-18T16:09:04.510863Z","end":"2026-06-18T16:09:04.806705Z","steps":["trace[363962521] 'agreement among raft nodes before linearized reading' (duration: 261.282406ms)","trace[363962521] 'range keys from in-memory index tree' (duration: 34.414974ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:08.218394Z","caller":"traceutil/trace.go:171","msg":"trace[1123182617] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"128.570106ms","start":"2026-06-18T16:09:08.089805Z","end":"2026-06-18T16:09:08.218375Z","steps":["trace[1123182617] 'process raft request' (duration: 128.460923ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:08.383627Z","caller":"traceutil/trace.go:171","msg":"trace[455428843] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"158.439466ms","start":"2026-06-18T16:09:08.22516Z","end":"2026-06-18T16:09:08.3836Z","steps":["trace[455428843] 'process raft request' (duration: 88.199533ms)","trace[455428843] 'compare' (duration: 70.12998ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:08.581842Z","caller":"traceutil/trace.go:171","msg":"trace[939536683] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"353.169152ms","start":"2026-06-18T16:09:08.228651Z","end":"2026-06-18T16:09:08.58182Z","steps":["trace[939536683] 'process raft request' (duration: 353.118061ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:08.582061Z","caller":"traceutil/trace.go:171","msg":"trace[1770171528] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"356.488755ms","start":"2026-06-18T16:09:08.225542Z","end":"2026-06-18T16:09:08.582031Z","steps":["trace[1770171528] 'process raft request' (duration: 337.50159ms)","trace[1770171528] 'compare' (duration: 18.586215ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:09:08.582151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:08.225523Z","time spent":"356.575128ms","remote":"127.0.0.1:34582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:09:08.582514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:08.228626Z","time spent":"353.279645ms","remote":"127.0.0.1:34760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:09:09.512716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.204142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T16:09:09.512798Z","caller":"traceutil/trace.go:171","msg":"trace[1098699228] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"782.22058ms","start":"2026-06-18T16:09:08.730564Z","end":"2026-06-18T16:09:09.512785Z","steps":["trace[1098699228] 'read index received' (duration: 222.883564ms)","trace[1098699228] 'applied index is now lower than readState.Index' (duration: 559.335976ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:09:09.513059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.501857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T16:09:09.513099Z","caller":"traceutil/trace.go:171","msg":"trace[1189010838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1248; }","duration":"729.568829ms","start":"2026-06-18T16:09:08.783519Z","end":"2026-06-18T16:09:09.513087Z","steps":["trace[1189010838] 'agreement among raft nodes before linearized reading' (duration: 729.458546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:09.513135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:08.783502Z","time spent":"729.62574ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T16:09:09.513193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.634916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-18T16:09:09.513222Z","caller":"traceutil/trace.go:171","msg":"trace[274593309] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1248; }","duration":"687.688487ms","start":"2026-06-18T16:09:08.825525Z","end":"2026-06-18T16:09:09.513214Z","steps":["trace[274593309] 'agreement among raft nodes before linearized reading' (duration: 687.617335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:09.513227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.857421ms","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-06-18T16:09:09.513275Z","caller":"traceutil/trace.go:171","msg":"trace[1102892679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1248; }","duration":"683.906772ms","start":"2026-06-18T16:09:08.829357Z","end":"2026-06-18T16:09:09.513263Z","steps":["trace[1102892679] 'agreement among raft nodes before linearized reading' (duration: 683.778679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:09.51329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.443056ms","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-06-18T16:09:09.513309Z","caller":"traceutil/trace.go:171","msg":"trace[597729452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1248; }","duration":"684.505288ms","start":"2026-06-18T16:09:08.828798Z","end":"2026-06-18T16:09:09.513304Z","steps":["trace[597729452] 'agreement among raft nodes before linearized reading' (duration: 684.455187ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:09.513312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:08.829334Z","time spent":"683.971353ms","remote":"127.0.0.1:34568","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-06-18T16:09:09.513332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:08.828774Z","time spent":"684.552449ms","remote":"127.0.0.1:34568","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-06-18T16:09:09.513242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:08.825509Z","time spent":"687.726868ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-18T16:09:09.513343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.16905ms","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-06-18T16:09:09.513396Z","caller":"traceutil/trace.go:171","msg":"trace[1606960217] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"792.848186ms","start":"2026-06-18T16:09:08.720536Z","end":"2026-06-18T16:09:09.513384Z","steps":["trace[1606960217] 'process raft request' (duration: 232.910165ms)","trace[1606960217] 'compare' (duration: 558.680219ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:09.513409Z","caller":"traceutil/trace.go:171","msg":"trace[2098077412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1248; }","duration":"690.265362ms","start":"2026-06-18T16:09:08.823126Z","end":"2026-06-18T16:09:09.513392Z","steps":["trace[2098077412] 'agreement among raft nodes before linearized reading' (duration: 690.066067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:09.513453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:08.82306Z","time spent":"690.382185ms","remote":"127.0.0.1:34568","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-06-18T16:09:09.51346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:08.720511Z","time spent":"792.913007ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:09:09.513755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.908627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.83\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-18T16:09:09.513818Z","caller":"traceutil/trace.go:171","msg":"trace[1393109706] range","detail":"{range_begin:/registry/masterleases/199.19.213.83; range_end:; response_count:1; response_revision:1248; }","duration":"783.269836ms","start":"2026-06-18T16:09:08.730531Z","end":"2026-06-18T16:09:09.513801Z","steps":["trace[1393109706] 'agreement among raft nodes before linearized reading' (duration: 782.823335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:09.513849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:08.73051Z","time spent":"783.332907ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.83\" "} {"level":"warn","ts":"2026-06-18T16:09:10.003649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:09.514525Z","time spent":"489.117985ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-18T16:09:10.014457Z","caller":"traceutil/trace.go:171","msg":"trace[1178019686] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"497.612077ms","start":"2026-06-18T16:09:09.516811Z","end":"2026-06-18T16:09:10.014423Z","steps":["trace[1178019686] 'process raft request' (duration: 497.558976ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:10.014598Z","caller":"traceutil/trace.go:171","msg":"trace[737097645] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"496.952581ms","start":"2026-06-18T16:09:09.517629Z","end":"2026-06-18T16:09:10.014581Z","steps":["trace[737097645] 'process raft request' (duration: 496.767096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:10.014679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:09.51762Z","time spent":"496.996782ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T16:09:10.014686Z","caller":"traceutil/trace.go:171","msg":"trace[1096783053] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1305; }","duration":"272.222801ms","start":"2026-06-18T16:09:09.742445Z","end":"2026-06-18T16:09:10.014668Z","steps":["trace[1096783053] 'read index received' (duration: 224.873513ms)","trace[1096783053] 'applied index is now lower than readState.Index' (duration: 47.348278ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:10.014731Z","caller":"traceutil/trace.go:171","msg":"trace[917909010] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"497.009452ms","start":"2026-06-18T16:09:09.51771Z","end":"2026-06-18T16:09:10.014719Z","steps":["trace[917909010] 'process raft request' (duration: 496.828188ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:10.014764Z","caller":"traceutil/trace.go:171","msg":"trace[390541649] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"496.992872ms","start":"2026-06-18T16:09:09.517755Z","end":"2026-06-18T16:09:10.014748Z","steps":["trace[390541649] 'process raft request' (duration: 496.874899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:10.014809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.355644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T16:09:10.014826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:09.51774Z","time spent":"497.056443ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T16:09:10.014838Z","caller":"traceutil/trace.go:171","msg":"trace[302611229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"272.437517ms","start":"2026-06-18T16:09:09.742393Z","end":"2026-06-18T16:09:10.01483Z","steps":["trace[302611229] 'agreement among raft nodes before linearized reading' (duration: 272.344875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:10.014879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:09.517705Z","time spent":"497.073124ms","remote":"127.0.0.1:34568","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-06-18T16:09:10.014914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:09.516805Z","time spent":"497.75506ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T16:09:10.014483Z","caller":"traceutil/trace.go:171","msg":"trace[1676878250] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"497.70827ms","start":"2026-06-18T16:09:09.516743Z","end":"2026-06-18T16:09:10.014451Z","steps":["trace[1676878250] 'process raft request' (duration: 497.455764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:10.015067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:09.516726Z","time spent":"498.242283ms","remote":"127.0.0.1:34568","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-06-18T16:09:11.078945Z","caller":"traceutil/trace.go:171","msg":"trace[1648026798] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1312; }","duration":"150.932987ms","start":"2026-06-18T16:09:10.927969Z","end":"2026-06-18T16:09:11.078902Z","steps":["trace[1648026798] 'read index received' (duration: 150.752723ms)","trace[1648026798] 'applied index is now lower than readState.Index' (duration: 179.514µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:11.078961Z","caller":"traceutil/trace.go:171","msg":"trace[227262874] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"241.991843ms","start":"2026-06-18T16:09:10.836945Z","end":"2026-06-18T16:09:11.078937Z","steps":["trace[227262874] 'process raft request' (duration: 241.823079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:11.079064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.072471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T16:09:11.079129Z","caller":"traceutil/trace.go:171","msg":"trace[280100251] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1255; }","duration":"151.172723ms","start":"2026-06-18T16:09:10.92794Z","end":"2026-06-18T16:09:11.079113Z","steps":["trace[280100251] 'agreement among raft nodes before linearized reading' (duration: 151.066181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:12.241594Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700324418879642593,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T16:09:12.638334Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.120303933s","expected-duration":"1s"} {"level":"info","ts":"2026-06-18T16:09:12.638715Z","caller":"traceutil/trace.go:171","msg":"trace[1571083502] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"897.42039ms","start":"2026-06-18T16:09:11.741272Z","end":"2026-06-18T16:09:12.638692Z","steps":["trace[1571083502] 'read index received' (duration: 897.194424ms)","trace[1571083502] 'applied index is now lower than readState.Index' (duration: 224.306µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:12.638831Z","caller":"traceutil/trace.go:171","msg":"trace[831926396] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"1.121067202s","start":"2026-06-18T16:09:11.517744Z","end":"2026-06-18T16:09:12.638811Z","steps":["trace[831926396] 'process raft request' (duration: 1.120779095s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:12.638905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.595983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:09:12.638978Z","caller":"traceutil/trace.go:171","msg":"trace[1138859722] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1256; }","duration":"628.721497ms","start":"2026-06-18T16:09:12.01024Z","end":"2026-06-18T16:09:12.638961Z","steps":["trace[1138859722] 'agreement among raft nodes before linearized reading' (duration: 628.594303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:12.639029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.758938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T16:09:12.639033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:12.010222Z","time spent":"628.796289ms","remote":"127.0.0.1:34614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-06-18T16:09:12.639086Z","caller":"traceutil/trace.go:171","msg":"trace[1245415545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"897.839331ms","start":"2026-06-18T16:09:11.74123Z","end":"2026-06-18T16:09:12.639069Z","steps":["trace[1245415545] 'agreement among raft nodes before linearized reading' (duration: 897.755778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:12.639131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:11.741213Z","time spent":"897.907783ms","remote":"127.0.0.1:34292","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-06-18T16:09:12.639176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.617964ms","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-06-18T16:09:12.639211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.728087ms","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-06-18T16:09:12.639258Z","caller":"traceutil/trace.go:171","msg":"trace[1166785382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1256; }","duration":"620.730408ms","start":"2026-06-18T16:09:12.018509Z","end":"2026-06-18T16:09:12.63924Z","steps":["trace[1166785382] 'agreement among raft nodes before linearized reading' (duration: 620.45912ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:12.639274Z","caller":"traceutil/trace.go:171","msg":"trace[644231735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1256; }","duration":"620.813149ms","start":"2026-06-18T16:09:12.018443Z","end":"2026-06-18T16:09:12.639256Z","steps":["trace[644231735] 'agreement among raft nodes before linearized reading' (duration: 620.610904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:12.639253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.957297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-18T16:09:12.639276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.863645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-18T16:09:12.639324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:12.018432Z","time spent":"620.879001ms","remote":"127.0.0.1:34568","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-06-18T16:09:12.639382Z","caller":"traceutil/trace.go:171","msg":"trace[1877583398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1256; }","duration":"618.132032ms","start":"2026-06-18T16:09:12.02122Z","end":"2026-06-18T16:09:12.639352Z","steps":["trace[1877583398] 'agreement among raft nodes before linearized reading' (duration: 617.744652ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:12.639389Z","caller":"traceutil/trace.go:171","msg":"trace[2126301364] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1256; }","duration":"618.04348ms","start":"2026-06-18T16:09:12.021321Z","end":"2026-06-18T16:09:12.639365Z","steps":["trace[2126301364] 'agreement among raft nodes before linearized reading' (duration: 617.744442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:12.639407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.883906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T16:09:12.63944Z","caller":"traceutil/trace.go:171","msg":"trace[240026648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1256; }","duration":"619.958928ms","start":"2026-06-18T16:09:12.019472Z","end":"2026-06-18T16:09:12.639431Z","steps":["trace[240026648] 'agreement among raft nodes before linearized reading' (duration: 619.861596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:12.639447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:12.02121Z","time spent":"618.226984ms","remote":"127.0.0.1:34568","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-06-18T16:09:12.639448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:12.021314Z","time spent":"618.125131ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-18T16:09:12.639476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:12.019449Z","time spent":"620.018519ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T16:09:12.639306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:12.018503Z","time spent":"620.793909ms","remote":"127.0.0.1:34568","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-06-18T16:09:12.639714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:11.517722Z","time spent":"1.121165104s","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:09:13.563748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.053475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T16:09:13.563928Z","caller":"traceutil/trace.go:171","msg":"trace[473206657] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"919.964176ms","start":"2026-06-18T16:09:12.64395Z","end":"2026-06-18T16:09:13.563914Z","steps":["trace[473206657] 'process raft request' (duration: 919.910525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:13.563986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:12.643941Z","time spent":"920.011077ms","remote":"127.0.0.1:34568","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-06-18T16:09:13.563976Z","caller":"traceutil/trace.go:171","msg":"trace[114429138] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"920.494659ms","start":"2026-06-18T16:09:12.643437Z","end":"2026-06-18T16:09:13.563932Z","steps":["trace[114429138] 'process raft request' (duration: 350.197808ms)","trace[114429138] 'compare' (duration: 569.823249ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:13.564012Z","caller":"traceutil/trace.go:171","msg":"trace[447399532] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"920.493409ms","start":"2026-06-18T16:09:12.643481Z","end":"2026-06-18T16:09:13.563974Z","steps":["trace[447399532] 'process raft request' (duration: 920.344566ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:13.564152Z","caller":"traceutil/trace.go:171","msg":"trace[1775210819] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1314; }","duration":"836.113352ms","start":"2026-06-18T16:09:12.72803Z","end":"2026-06-18T16:09:13.564143Z","steps":["trace[1775210819] 'read index received' (duration: 265.570924ms)","trace[1775210819] 'applied index is now lower than readState.Index' (duration: 570.541568ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:09:13.564162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:12.64341Z","time spent":"920.642572ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:09:13.564199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:12.64346Z","time spent":"920.633832ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:09:13.564235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.197344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:09:13.564265Z","caller":"traceutil/trace.go:171","msg":"trace[1976798049] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1261; }","duration":"836.264065ms","start":"2026-06-18T16:09:12.727992Z","end":"2026-06-18T16:09:13.564256Z","steps":["trace[1976798049] 'agreement among raft nodes before linearized reading' (duration: 836.196334ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:13.564047Z","caller":"traceutil/trace.go:171","msg":"trace[923149558] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"920.013637ms","start":"2026-06-18T16:09:12.644018Z","end":"2026-06-18T16:09:13.564031Z","steps":["trace[923149558] 'process raft request' (duration: 919.864013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:13.564318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:12.644014Z","time spent":"920.284524ms","remote":"127.0.0.1:34568","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-06-18T16:09:13.564305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:12.727963Z","time spent":"836.333257ms","remote":"127.0.0.1:37616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-18T16:09:13.56435Z","caller":"traceutil/trace.go:171","msg":"trace[1583104391] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"920.255004ms","start":"2026-06-18T16:09:12.644085Z","end":"2026-06-18T16:09:13.56434Z","steps":["trace[1583104391] 'process raft request' (duration: 919.901515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:13.564414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:12.64408Z","time spent":"920.292994ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T16:09:14.065365Z","caller":"traceutil/trace.go:171","msg":"trace[34087984] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"324.135745ms","start":"2026-06-18T16:09:13.741201Z","end":"2026-06-18T16:09:14.065337Z","steps":["trace[34087984] 'read index received' (duration: 323.623111ms)","trace[34087984] 'applied index is now lower than readState.Index' (duration: 511.314µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:09:14.06547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.269007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:09:14.065506Z","caller":"traceutil/trace.go:171","msg":"trace[1926179829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"324.382571ms","start":"2026-06-18T16:09:13.741114Z","end":"2026-06-18T16:09:14.065496Z","steps":["trace[1926179829] 'agreement among raft nodes before linearized reading' (duration: 324.266638ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:14.065537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:13.741093Z","time spent":"324.435871ms","remote":"127.0.0.1:34292","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-06-18T16:09:14.065754Z","caller":"traceutil/trace.go:171","msg":"trace[721701428] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"430.530704ms","start":"2026-06-18T16:09:13.634819Z","end":"2026-06-18T16:09:14.06535Z","steps":["trace[721701428] 'process raft request' (duration: 430.114904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:14.065846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:13.63478Z","time spent":"431.000286ms","remote":"127.0.0.1:34362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T16:09:15.241989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700324418879642607,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T16:09:15.561531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366948753s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-18T16:09:15.562063Z","caller":"traceutil/trace.go:171","msg":"trace[386102408] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1320; }","duration":"820.170072ms","start":"2026-06-18T16:09:14.741867Z","end":"2026-06-18T16:09:15.562037Z","steps":["trace[386102408] 'read index received' (duration: 719.688212ms)","trace[386102408] 'applied index is now lower than readState.Index' (duration: 100.47997ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:15.562085Z","caller":"traceutil/trace.go:171","msg":"trace[432004135] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"917.649868ms","start":"2026-06-18T16:09:14.6444Z","end":"2026-06-18T16:09:15.56205Z","steps":["trace[432004135] 'process raft request' (duration: 917.237038ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:15.562287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:14.644376Z","time spent":"917.826283ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:09:16.109091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367214408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:09:16.109201Z","caller":"traceutil/trace.go:171","msg":"trace[500478910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"1.367368622s","start":"2026-06-18T16:09:14.741803Z","end":"2026-06-18T16:09:16.109171Z","steps":["trace[500478910] 'agreement among raft nodes before linearized reading' (duration: 820.312436ms)","trace[500478910] 'range keys from in-memory index tree' (duration: 546.912843ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:09:16.109258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:14.741771Z","time spent":"1.367472994s","remote":"127.0.0.1:34292","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-06-18T16:09:16.109287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.26762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T16:09:16.109413Z","caller":"traceutil/trace.go:171","msg":"trace[485013185] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"547.263232ms","start":"2026-06-18T16:09:15.562129Z","end":"2026-06-18T16:09:16.109392Z","steps":["trace[485013185] 'read index received' (duration: 21.752156ms)","trace[485013185] 'applied index is now lower than readState.Index' (duration: 525.509616ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:09:16.109583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111580293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T16:09:16.109659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.103482ms","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-06-18T16:09:16.109668Z","caller":"traceutil/trace.go:171","msg":"trace[2005620783] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1264; }","duration":"1.111676245s","start":"2026-06-18T16:09:14.997967Z","end":"2026-06-18T16:09:16.109643Z","steps":["trace[2005620783] 'agreement among raft nodes before linearized reading' (duration: 1.111542522s)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:16.109704Z","caller":"traceutil/trace.go:171","msg":"trace[1321249130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1264; }","duration":"542.180414ms","start":"2026-06-18T16:09:15.567509Z","end":"2026-06-18T16:09:16.10969Z","steps":["trace[1321249130] 'agreement among raft nodes before linearized reading' (duration: 542.01546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:16.109725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:14.99791Z","time spent":"1.111798277s","remote":"127.0.0.1:37508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-06-18T16:09:16.109748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:15.567494Z","time spent":"542.242335ms","remote":"127.0.0.1:34568","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-06-18T16:09:16.1098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.032935ms","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-06-18T16:09:16.109839Z","caller":"traceutil/trace.go:171","msg":"trace[1060983043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"541.091107ms","start":"2026-06-18T16:09:15.568737Z","end":"2026-06-18T16:09:16.109828Z","steps":["trace[1060983043] 'agreement among raft nodes before linearized reading' (duration: 540.943744ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:16.109873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:15.56873Z","time spent":"541.133758ms","remote":"127.0.0.1:34568","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":"warn","ts":"2026-06-18T16:09:16.109938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.777604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-18T16:09:16.109993Z","caller":"traceutil/trace.go:171","msg":"trace[547218766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1264; }","duration":"541.866847ms","start":"2026-06-18T16:09:15.568112Z","end":"2026-06-18T16:09:16.109979Z","steps":["trace[547218766] 'agreement among raft nodes before linearized reading' (duration: 541.650971ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:16.109995Z","caller":"traceutil/trace.go:171","msg":"trace[2086993584] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"728.709543ms","start":"2026-06-18T16:09:15.381264Z","end":"2026-06-18T16:09:16.109973Z","steps":["trace[2086993584] 'process raft request' (duration: 202.663703ms)","trace[2086993584] 'compare' (duration: 524.925041ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:09:16.110035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:15.568106Z","time spent":"541.917118ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T16:09:16.110048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.96112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-18T16:09:16.110094Z","caller":"traceutil/trace.go:171","msg":"trace[1014119697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1264; }","duration":"542.022961ms","start":"2026-06-18T16:09:15.568058Z","end":"2026-06-18T16:09:16.110081Z","steps":["trace[1014119697] 'agreement among raft nodes before linearized reading' (duration: 541.895778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:16.1101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:15.381227Z","time spent":"728.806114ms","remote":"127.0.0.1:34568","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-06-18T16:09:16.110144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:15.56805Z","time spent":"542.083063ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-18T16:09:16.110275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.087802ms","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-06-18T16:09:16.110331Z","caller":"traceutil/trace.go:171","msg":"trace[868536404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1264; }","duration":"542.180834ms","start":"2026-06-18T16:09:15.568136Z","end":"2026-06-18T16:09:16.110317Z","steps":["trace[868536404] 'agreement among raft nodes before linearized reading' (duration: 542.00656ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:16.110353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.247566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T16:09:16.110382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:15.568117Z","time spent":"542.250537ms","remote":"127.0.0.1:34568","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-06-18T16:09:16.110398Z","caller":"traceutil/trace.go:171","msg":"trace[191690439] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1264; }","duration":"268.335248ms","start":"2026-06-18T16:09:15.84205Z","end":"2026-06-18T16:09:16.110385Z","steps":["trace[191690439] 'agreement among raft nodes before linearized reading' (duration: 268.257616ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:16.656775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.516053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T16:09:16.656967Z","caller":"traceutil/trace.go:171","msg":"trace[631816424] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"541.839327ms","start":"2026-06-18T16:09:16.11511Z","end":"2026-06-18T16:09:16.656949Z","steps":["trace[631816424] 'process raft request' (duration: 541.752755ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:16.656998Z","caller":"traceutil/trace.go:171","msg":"trace[1505478330] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"541.840087ms","start":"2026-06-18T16:09:16.115119Z","end":"2026-06-18T16:09:16.656959Z","steps":["trace[1505478330] 'process raft request' (duration: 349.670878ms)","trace[1505478330] 'compare' (duration: 191.428961ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:09:16.657036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:16.115084Z","time spent":"541.921418ms","remote":"127.0.0.1:34568","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-06-18T16:09:16.657321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:16.115104Z","time spent":"541.986601ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T16:09:16.877757Z","caller":"traceutil/trace.go:171","msg":"trace[1579398555] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1323; }","duration":"759.578237ms","start":"2026-06-18T16:09:16.118157Z","end":"2026-06-18T16:09:16.877735Z","steps":["trace[1579398555] 'read index received' (duration: 346.763476ms)","trace[1579398555] 'applied index is now lower than readState.Index' (duration: 412.813531ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:16.877881Z","caller":"traceutil/trace.go:171","msg":"trace[546327376] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"762.513161ms","start":"2026-06-18T16:09:16.115355Z","end":"2026-06-18T16:09:16.877868Z","steps":["trace[546327376] 'process raft request' (duration: 730.99713ms)","trace[546327376] 'compare' (duration: 31.156631ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T16:09:16.877973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:16.115247Z","time spent":"762.662924ms","remote":"127.0.0.1:34568","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-06-18T16:09:16.878087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.920306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:09:16.878142Z","caller":"traceutil/trace.go:171","msg":"trace[1833873287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1269; }","duration":"759.998667ms","start":"2026-06-18T16:09:16.118131Z","end":"2026-06-18T16:09:16.878129Z","steps":["trace[1833873287] 'agreement among raft nodes before linearized reading' (duration: 759.914295ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:16.878245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:16.118121Z","time spent":"760.094479ms","remote":"127.0.0.1:34292","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-06-18T16:09:16.878315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.460353ms","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":"info","ts":"2026-06-18T16:09:16.878381Z","caller":"traceutil/trace.go:171","msg":"trace[1954458976] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1269; }","duration":"617.546916ms","start":"2026-06-18T16:09:16.260812Z","end":"2026-06-18T16:09:16.878359Z","steps":["trace[1954458976] 'agreement among raft nodes before linearized reading' (duration: 617.448703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:16.878433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:16.26078Z","time spent":"617.639317ms","remote":"127.0.0.1:37586","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-06-18T16:09:16.878485Z","caller":"traceutil/trace.go:171","msg":"trace[1720368727] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"763.076114ms","start":"2026-06-18T16:09:16.115387Z","end":"2026-06-18T16:09:16.878463Z","steps":["trace[1720368727] 'process raft request' (duration: 762.264504ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:16.878492Z","caller":"traceutil/trace.go:171","msg":"trace[1235323549] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"761.927134ms","start":"2026-06-18T16:09:16.116544Z","end":"2026-06-18T16:09:16.878471Z","steps":["trace[1235323549] 'process raft request' (duration: 761.153695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:16.878585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:16.115347Z","time spent":"763.187727ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:09:16.878707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:16.116526Z","time spent":"762.080849ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T16:09:20.206085Z","caller":"traceutil/trace.go:171","msg":"trace[1269528976] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"464.498938ms","start":"2026-06-18T16:09:19.741554Z","end":"2026-06-18T16:09:20.206053Z","steps":["trace[1269528976] 'read index received' (duration: 464.255092ms)","trace[1269528976] 'applied index is now lower than readState.Index' (duration: 242.496µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:20.206203Z","caller":"traceutil/trace.go:171","msg":"trace[749888559] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"626.681414ms","start":"2026-06-18T16:09:19.579443Z","end":"2026-06-18T16:09:20.206124Z","steps":["trace[749888559] 'process raft request' (duration: 626.456729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:20.206275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.703202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:09:20.206345Z","caller":"traceutil/trace.go:171","msg":"trace[876058478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"464.825055ms","start":"2026-06-18T16:09:19.741503Z","end":"2026-06-18T16:09:20.206328Z","steps":["trace[876058478] 'agreement among raft nodes before linearized reading' (duration: 464.653941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:20.206353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:19.57942Z","time spent":"626.840138ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T16:09:20.206378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:19.741482Z","time spent":"464.889808ms","remote":"127.0.0.1:34292","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-06-18T16:09:21.264972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.757473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-18T16:09:21.264987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.460124ms","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-06-18T16:09:21.265047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.092706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T16:09:21.265063Z","caller":"traceutil/trace.go:171","msg":"trace[2113338519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1290; }","duration":"375.580807ms","start":"2026-06-18T16:09:20.889465Z","end":"2026-06-18T16:09:21.265046Z","steps":["trace[2113338519] 'range keys from in-memory index tree' (duration: 375.238099ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:21.265053Z","caller":"traceutil/trace.go:171","msg":"trace[185751892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1290; }","duration":"592.874697ms","start":"2026-06-18T16:09:20.672158Z","end":"2026-06-18T16:09:21.265033Z","steps":["trace[185751892] 'range keys from in-memory index tree' (duration: 592.618341ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:21.265095Z","caller":"traceutil/trace.go:171","msg":"trace[1683528944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1290; }","duration":"375.158087ms","start":"2026-06-18T16:09:20.889924Z","end":"2026-06-18T16:09:21.265082Z","steps":["trace[1683528944] 'range keys from in-memory index tree' (duration: 374.999324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:21.265114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:20.889446Z","time spent":"375.65829ms","remote":"127.0.0.1:34568","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-06-18T16:09:21.265128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:20.889917Z","time spent":"375.204178ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T16:09:21.265133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:20.672152Z","time spent":"592.970299ms","remote":"127.0.0.1:34568","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-06-18T16:09:21.265666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.429805ms","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-06-18T16:09:21.265706Z","caller":"traceutil/trace.go:171","msg":"trace[244795269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1290; }","duration":"596.498247ms","start":"2026-06-18T16:09:20.669198Z","end":"2026-06-18T16:09:21.265696Z","steps":["trace[244795269] 'range keys from in-memory index tree' (duration: 596.331473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:21.265734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:20.669182Z","time spent":"596.545818ms","remote":"127.0.0.1:34568","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-06-18T16:09:21.265864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.287975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-18T16:09:21.265889Z","caller":"traceutil/trace.go:171","msg":"trace[479146346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1290; }","duration":"376.324396ms","start":"2026-06-18T16:09:20.889556Z","end":"2026-06-18T16:09:21.26588Z","steps":["trace[479146346] 'range keys from in-memory index tree' (duration: 376.216064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:21.265915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:20.88955Z","time spent":"376.358177ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-18T16:09:21.266248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.581594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:09:21.266331Z","caller":"traceutil/trace.go:171","msg":"trace[1504566701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"523.703812ms","start":"2026-06-18T16:09:20.742604Z","end":"2026-06-18T16:09:21.266308Z","steps":["trace[1504566701] 'range keys from in-memory index tree' (duration: 522.514642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:21.266382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:20.742586Z","time spent":"523.778944ms","remote":"127.0.0.1:34292","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-06-18T16:09:22.795792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.717971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T16:09:22.795882Z","caller":"traceutil/trace.go:171","msg":"trace[526117527] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1300; }","duration":"310.856254ms","start":"2026-06-18T16:09:22.485001Z","end":"2026-06-18T16:09:22.795857Z","steps":["trace[526117527] 'range keys from in-memory index tree' (duration: 310.641989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:22.795866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.18535ms","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-06-18T16:09:22.795949Z","caller":"traceutil/trace.go:171","msg":"trace[1724682841] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1300; }","duration":"141.284612ms","start":"2026-06-18T16:09:22.654646Z","end":"2026-06-18T16:09:22.795931Z","steps":["trace[1724682841] 'count revisions from in-memory index tree' (duration: 141.084307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:22.795956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:22.484986Z","time spent":"310.959657ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-18T16:09:23.418291Z","caller":"traceutil/trace.go:171","msg":"trace[1272044362] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"135.447476ms","start":"2026-06-18T16:09:23.282813Z","end":"2026-06-18T16:09:23.418261Z","steps":["trace[1272044362] 'read index received' (duration: 135.403805ms)","trace[1272044362] 'applied index is now lower than readState.Index' (duration: 42.171µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:23.418461Z","caller":"traceutil/trace.go:171","msg":"trace[9433077] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"135.749393ms","start":"2026-06-18T16:09:23.282688Z","end":"2026-06-18T16:09:23.418438Z","steps":["trace[9433077] 'process raft request' (duration: 135.337553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:23.418591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.786834ms","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-06-18T16:09:23.418637Z","caller":"traceutil/trace.go:171","msg":"trace[822552009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1306; }","duration":"135.864556ms","start":"2026-06-18T16:09:23.282759Z","end":"2026-06-18T16:09:23.418623Z","steps":["trace[822552009] 'agreement among raft nodes before linearized reading' (duration: 135.664811ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:23.495402Z","caller":"traceutil/trace.go:171","msg":"trace[457250528] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"208.712604ms","start":"2026-06-18T16:09:23.286665Z","end":"2026-06-18T16:09:23.495377Z","steps":["trace[457250528] 'process raft request' (duration: 208.657423ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:23.495577Z","caller":"traceutil/trace.go:171","msg":"trace[1008609488] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"209.898533ms","start":"2026-06-18T16:09:23.285663Z","end":"2026-06-18T16:09:23.495562Z","steps":["trace[1008609488] 'process raft request' (duration: 209.349079ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:23.495721Z","caller":"traceutil/trace.go:171","msg":"trace[1403953032] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"209.211226ms","start":"2026-06-18T16:09:23.286479Z","end":"2026-06-18T16:09:23.49569Z","steps":["trace[1403953032] 'process raft request' (duration: 208.808016ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:23.581092Z","caller":"traceutil/trace.go:171","msg":"trace[1884687936] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"157.94734ms","start":"2026-06-18T16:09:23.423117Z","end":"2026-06-18T16:09:23.581065Z","steps":["trace[1884687936] 'process raft request' (duration: 119.414344ms)","trace[1884687936] 'compare' (duration: 38.353942ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:28.91993Z","caller":"traceutil/trace.go:171","msg":"trace[1803181860] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1407; }","duration":"187.911682ms","start":"2026-06-18T16:09:28.731989Z","end":"2026-06-18T16:09:28.919901Z","steps":["trace[1803181860] 'read index received' (duration: 187.674156ms)","trace[1803181860] 'applied index is now lower than readState.Index' (duration: 236.166µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:28.920284Z","caller":"traceutil/trace.go:171","msg":"trace[657659447] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"379.807232ms","start":"2026-06-18T16:09:28.540436Z","end":"2026-06-18T16:09:28.920243Z","steps":["trace[657659447] 'process raft request' (duration: 379.30608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:28.920409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.394433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.83\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-18T16:09:28.920471Z","caller":"traceutil/trace.go:171","msg":"trace[16102557] range","detail":"{range_begin:/registry/masterleases/199.19.213.83; range_end:; response_count:1; response_revision:1347; }","duration":"188.499606ms","start":"2026-06-18T16:09:28.731951Z","end":"2026-06-18T16:09:28.920451Z","steps":["trace[16102557] 'agreement among raft nodes before linearized reading' (duration: 188.077586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T16:09:28.920475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T16:09:28.540403Z","time spent":"379.971836ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T16:09:35.831029Z","caller":"traceutil/trace.go:171","msg":"trace[24268419] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"187.886251ms","start":"2026-06-18T16:09:35.643085Z","end":"2026-06-18T16:09:35.830971Z","steps":["trace[24268419] 'process raft request' (duration: 167.364337ms)","trace[24268419] 'compare' (duration: 20.190536ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T16:09:35.831158Z","caller":"traceutil/trace.go:171","msg":"trace[597477093] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"187.637005ms","start":"2026-06-18T16:09:35.643481Z","end":"2026-06-18T16:09:35.831118Z","steps":["trace[597477093] 'process raft request' (duration: 187.278776ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:42.094586Z","caller":"traceutil/trace.go:171","msg":"trace[1253302750] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"182.937246ms","start":"2026-06-18T16:09:41.911623Z","end":"2026-06-18T16:09:42.09456Z","steps":["trace[1253302750] 'process raft request' (duration: 182.802643ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:42.096244Z","caller":"traceutil/trace.go:171","msg":"trace[1713086044] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"183.520422ms","start":"2026-06-18T16:09:41.91259Z","end":"2026-06-18T16:09:42.096111Z","steps":["trace[1713086044] 'process raft request' (duration: 183.342267ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T16:09:43.932832Z","caller":"traceutil/trace.go:171","msg":"trace[376878326] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"159.892749ms","start":"2026-06-18T16:09:43.772917Z","end":"2026-06-18T16:09:43.93281Z","steps":["trace[376878326] 'process raft request' (duration: 69.425831ms)","trace[376878326] 'compare' (duration: 90.353335ms)"],"step_count":2}