{"level":"warn","ts":"2026-08-16T12:51:48.573873Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:51:48.573946Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-08-16T12:51:48.573997Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:51:48.574005Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-08-16T12:51:48.574019Z","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-08-16T12:51:48.574599Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-08-16T12:51:48.574678Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-08-16T12:51:48.591836Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.965249ms"} {"level":"info","ts":"2026-08-16T12:51:48.643245Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-08-16T12:51:48.643354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-08-16T12:51:48.643381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-08-16T12:51:48.643409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-16T12:51:48.643428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-08-16T12:51:48.643485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-08-16T12:51:48.650476Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-16T12:51:48.651926Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-16T12:51:48.653431Z","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-08-16T12:51:48.656265Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-16T12:51:48.656391Z","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-08-16T12:51:48.656492Z","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-08-16T12:51:48.656504Z","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-08-16T12:51:48.656823Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-16T12:51:48.658961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-08-16T12:51:48.659206Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-08-16T12:51:48.662044Z","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-08-16T12:51:48.662228Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-08-16T12:51:48.662261Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-08-16T12:51:48.662252Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-16T12:51:48.662323Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-16T12:51:49.043724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-08-16T12:51:49.043813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-08-16T12:51:49.043853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-08-16T12:51:49.043876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-08-16T12:51:49.043893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-08-16T12:51:49.043912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-08-16T12:51:49.043931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-08-16T12:51:49.122185Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:51:49.139217Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-08-16T12:51:49.139389Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:51:49.140435Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:51:49.140855Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:51:49.140894Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:51:49.141193Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:51:49.141686Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-16T12:51:49.141747Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-16T12:51:49.143069Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-08-16T12:51:49.144094Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-16T12:52:06.254471Z","caller":"traceutil/trace.go:171","msg":"trace[1233961915] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"183.080278ms","start":"2026-08-16T12:52:06.071329Z","end":"2026-08-16T12:52:06.254409Z","steps":["trace[1233961915] 'process raft request' (duration: 182.855702ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:10.451739Z","caller":"traceutil/trace.go:171","msg":"trace[550748202] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"164.625007ms","start":"2026-08-16T12:52:10.287091Z","end":"2026-08-16T12:52:10.451716Z","steps":["trace[550748202] 'process raft request' (duration: 164.468063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:10.746214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.891472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-16T12:52:10.74631Z","caller":"traceutil/trace.go:171","msg":"trace[444254150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:383; }","duration":"267.065687ms","start":"2026-08-16T12:52:10.479228Z","end":"2026-08-16T12:52:10.746294Z","steps":["trace[444254150] 'range keys from in-memory index tree' (duration: 266.76735ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:26.237064Z","caller":"traceutil/trace.go:171","msg":"trace[652794957] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"171.965787ms","start":"2026-08-16T12:52:26.065064Z","end":"2026-08-16T12:52:26.23703Z","steps":["trace[652794957] 'process raft request' (duration: 171.74419ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:26.241889Z","caller":"traceutil/trace.go:171","msg":"trace[848084112] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"161.358707ms","start":"2026-08-16T12:52:26.080508Z","end":"2026-08-16T12:52:26.241867Z","steps":["trace[848084112] 'process raft request' (duration: 161.105471ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:26.242283Z","caller":"traceutil/trace.go:171","msg":"trace[1404746851] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"108.761824ms","start":"2026-08-16T12:52:26.133498Z","end":"2026-08-16T12:52:26.24226Z","steps":["trace[1404746851] 'process raft request' (duration: 108.725543ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:26.242344Z","caller":"traceutil/trace.go:171","msg":"trace[1841961832] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"161.583282ms","start":"2026-08-16T12:52:26.080754Z","end":"2026-08-16T12:52:26.242338Z","steps":["trace[1841961832] 'process raft request' (duration: 160.973218ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:26.242342Z","caller":"traceutil/trace.go:171","msg":"trace[1708652827] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"132.37942ms","start":"2026-08-16T12:52:26.109908Z","end":"2026-08-16T12:52:26.242287Z","steps":["trace[1708652827] 'process raft request' (duration: 132.099053ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:26.242387Z","caller":"traceutil/trace.go:171","msg":"trace[1536025642] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"132.435261ms","start":"2026-08-16T12:52:26.109898Z","end":"2026-08-16T12:52:26.242333Z","steps":["trace[1536025642] 'process raft request' (duration: 132.253187ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:26.242474Z","caller":"traceutil/trace.go:171","msg":"trace[1278911026] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"157.991075ms","start":"2026-08-16T12:52:26.084473Z","end":"2026-08-16T12:52:26.242464Z","steps":["trace[1278911026] 'process raft request' (duration: 157.33063ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:26.242479Z","caller":"traceutil/trace.go:171","msg":"trace[17147918] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"159.473781ms","start":"2026-08-16T12:52:26.082982Z","end":"2026-08-16T12:52:26.242456Z","steps":["trace[17147918] 'process raft request' (duration: 158.784395ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:26.242526Z","caller":"traceutil/trace.go:171","msg":"trace[696376126] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"151.756533ms","start":"2026-08-16T12:52:26.090764Z","end":"2026-08-16T12:52:26.242521Z","steps":["trace[696376126] 'process raft request' (duration: 151.179729ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:26.242288Z","caller":"traceutil/trace.go:171","msg":"trace[264143012] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"152.47642ms","start":"2026-08-16T12:52:26.089777Z","end":"2026-08-16T12:52:26.242254Z","steps":["trace[264143012] 'process raft request' (duration: 152.067171ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:26.242681Z","caller":"traceutil/trace.go:171","msg":"trace[2147145710] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"130.751871ms","start":"2026-08-16T12:52:26.111849Z","end":"2026-08-16T12:52:26.242601Z","steps":["trace[2147145710] 'process raft request' (duration: 130.34017ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:32.189548Z","caller":"traceutil/trace.go:171","msg":"trace[2069597012] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"146.496065ms","start":"2026-08-16T12:52:32.043003Z","end":"2026-08-16T12:52:32.189499Z","steps":["trace[2069597012] 'read index received' (duration: 145.384948ms)","trace[2069597012] 'applied index is now lower than readState.Index' (duration: 1.110207ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:32.189647Z","caller":"traceutil/trace.go:171","msg":"trace[1143902617] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"158.387915ms","start":"2026-08-16T12:52:32.031211Z","end":"2026-08-16T12:52:32.189599Z","steps":["trace[1143902617] 'process raft request' (duration: 157.560135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:32.189744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.71112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-16T12:52:32.190035Z","caller":"traceutil/trace.go:171","msg":"trace[1255980611] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:591; }","duration":"147.041369ms","start":"2026-08-16T12:52:32.042975Z","end":"2026-08-16T12:52:32.190017Z","steps":["trace[1255980611] 'agreement among raft nodes before linearized reading' (duration: 146.659179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:32.745081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.37269ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-08-16T12:52:32.745389Z","caller":"traceutil/trace.go:171","msg":"trace[789033411] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"157.837463ms","start":"2026-08-16T12:52:32.587529Z","end":"2026-08-16T12:52:32.745366Z","steps":["trace[789033411] 'read index received' (duration: 35.581µs)","trace[789033411] 'applied index is now lower than readState.Index' (duration: 157.800732ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:52:32.745344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:32.19141Z","time spent":"553.928147ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-16T12:52:32.745467Z","caller":"traceutil/trace.go:171","msg":"trace[1561323790] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"504.641105ms","start":"2026-08-16T12:52:32.240775Z","end":"2026-08-16T12:52:32.745416Z","steps":["trace[1561323790] 'process raft request' (duration: 504.43098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:32.74561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.079198ms","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-08-16T12:52:32.745981Z","caller":"traceutil/trace.go:171","msg":"trace[765650598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"158.482018ms","start":"2026-08-16T12:52:32.587481Z","end":"2026-08-16T12:52:32.745963Z","steps":["trace[765650598] 'agreement among raft nodes before linearized reading' (duration: 157.999326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:32.746305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:32.240709Z","time spent":"505.149697ms","remote":"127.0.0.1:34844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:52:32.933746Z","caller":"traceutil/trace.go:171","msg":"trace[447093214] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"175.78461ms","start":"2026-08-16T12:52:32.75793Z","end":"2026-08-16T12:52:32.933715Z","steps":["trace[447093214] 'read index received' (duration: 25.643715ms)","trace[447093214] 'applied index is now lower than readState.Index' (duration: 150.139465ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:32.934003Z","caller":"traceutil/trace.go:171","msg":"trace[1158144009] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"182.677297ms","start":"2026-08-16T12:52:32.751267Z","end":"2026-08-16T12:52:32.933944Z","steps":["trace[1158144009] 'process raft request' (duration: 112.628558ms)","trace[1158144009] 'compare' (duration: 69.609049ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:52:32.934155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.227218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-16T12:52:32.934252Z","caller":"traceutil/trace.go:171","msg":"trace[1206409772] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:594; }","duration":"148.34054ms","start":"2026-08-16T12:52:32.785891Z","end":"2026-08-16T12:52:32.934231Z","steps":["trace[1206409772] 'agreement among raft nodes before linearized reading' (duration: 148.163156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:32.934324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.351084ms","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-08-16T12:52:32.934425Z","caller":"traceutil/trace.go:171","msg":"trace[160198618] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:594; }","duration":"176.516397ms","start":"2026-08-16T12:52:32.757886Z","end":"2026-08-16T12:52:32.934402Z","steps":["trace[160198618] 'agreement among raft nodes before linearized reading' (duration: 176.303502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:34.198168Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104428572715075,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:52:34.293421Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.354250596s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:52:34.29421Z","caller":"traceutil/trace.go:171","msg":"trace[2102406287] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.355077287s","start":"2026-08-16T12:52:32.939085Z","end":"2026-08-16T12:52:34.294162Z","steps":["trace[2102406287] 'process raft request' (duration: 1.354539943s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:34.29457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:32.939068Z","time spent":"1.355221841s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:52:34.304249Z","caller":"traceutil/trace.go:171","msg":"trace[1532418092] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.362595471s","start":"2026-08-16T12:52:32.941639Z","end":"2026-08-16T12:52:34.304234Z","steps":["trace[1532418092] 'process raft request' (duration: 1.3621539s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:34.304372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:32.94163Z","time spent":"1.362683653s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:52:34.304559Z","caller":"traceutil/trace.go:171","msg":"trace[394130365] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:620; }","duration":"606.670454ms","start":"2026-08-16T12:52:33.697846Z","end":"2026-08-16T12:52:34.304517Z","steps":["trace[394130365] 'read index received' (duration: 595.895421ms)","trace[394130365] 'applied index is now lower than readState.Index' (duration: 10.774283ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:34.304619Z","caller":"traceutil/trace.go:171","msg":"trace[363784210] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"959.462024ms","start":"2026-08-16T12:52:33.345135Z","end":"2026-08-16T12:52:34.304597Z","steps":["trace[363784210] 'process raft request' (duration: 958.90516ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:34.30407Z","caller":"traceutil/trace.go:171","msg":"trace[312415594] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.363599496s","start":"2026-08-16T12:52:32.94044Z","end":"2026-08-16T12:52:34.30404Z","steps":["trace[312415594] 'process raft request' (duration: 1.363182266s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:34.304769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:33.345108Z","time spent":"959.587146ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:52:34.304716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.853519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:52:34.304937Z","caller":"traceutil/trace.go:171","msg":"trace[103665832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"607.121855ms","start":"2026-08-16T12:52:33.697797Z","end":"2026-08-16T12:52:34.304919Z","steps":["trace[103665832] 'agreement among raft nodes before linearized reading' (duration: 606.829778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:34.304969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:33.697777Z","time spent":"607.182027ms","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T12:52:34.305253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:32.940428Z","time spent":"1.364388025s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:52:49.792551Z","caller":"traceutil/trace.go:171","msg":"trace[419005886] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"141.508763ms","start":"2026-08-16T12:52:49.650999Z","end":"2026-08-16T12:52:49.792507Z","steps":["trace[419005886] 'process raft request' (duration: 141.233786ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:52.306987Z","caller":"traceutil/trace.go:171","msg":"trace[547261401] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"355.826023ms","start":"2026-08-16T12:52:51.951139Z","end":"2026-08-16T12:52:52.306965Z","steps":["trace[547261401] 'read index received' (duration: 355.68518ms)","trace[547261401] 'applied index is now lower than readState.Index' (duration: 139.673µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:52:52.307112Z","caller":"traceutil/trace.go:171","msg":"trace[985163418] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"505.967277ms","start":"2026-08-16T12:52:51.801135Z","end":"2026-08-16T12:52:52.307102Z","steps":["trace[985163418] 'process raft request' (duration: 505.67432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:52.307214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:51.801116Z","time spent":"506.021328ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:52:52.307356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.193172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:52:52.307446Z","caller":"traceutil/trace.go:171","msg":"trace[1908316655] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:761; }","duration":"356.338175ms","start":"2026-08-16T12:52:51.951088Z","end":"2026-08-16T12:52:52.307426Z","steps":["trace[1908316655] 'agreement among raft nodes before linearized reading' (duration: 356.200132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:52.307495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:51.951063Z","time spent":"356.423207ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-08-16T12:52:52.34674Z","caller":"traceutil/trace.go:171","msg":"trace[1611727965] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"291.414721ms","start":"2026-08-16T12:52:52.055305Z","end":"2026-08-16T12:52:52.34672Z","steps":["trace[1611727965] 'process raft request' (duration: 291.310719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:52.347446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.069006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-16T12:52:52.347464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.828933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-16T12:52:52.347465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.25741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-16T12:52:52.347498Z","caller":"traceutil/trace.go:171","msg":"trace[2129364453] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:762; }","duration":"280.898735ms","start":"2026-08-16T12:52:52.066589Z","end":"2026-08-16T12:52:52.347488Z","steps":["trace[2129364453] 'agreement among raft nodes before linearized reading' (duration: 280.836003ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:52:52.347524Z","caller":"traceutil/trace.go:171","msg":"trace[1274613959] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:762; }","duration":"303.355722ms","start":"2026-08-16T12:52:52.044155Z","end":"2026-08-16T12:52:52.347511Z","steps":["trace[1274613959] 'agreement among raft nodes before linearized reading' (duration: 303.217849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:52.347553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:52.044131Z","time spent":"303.416424ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"info","ts":"2026-08-16T12:52:52.347485Z","caller":"traceutil/trace.go:171","msg":"trace[1723134645] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:762; }","duration":"326.143728ms","start":"2026-08-16T12:52:52.021326Z","end":"2026-08-16T12:52:52.34747Z","steps":["trace[1723134645] 'agreement among raft nodes before linearized reading' (duration: 326.070667ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:52.347708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:52.021312Z","time spent":"326.386974ms","remote":"127.0.0.1:34528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:52:52.347886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.169045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8qfln\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-16T12:52:52.347919Z","caller":"traceutil/trace.go:171","msg":"trace[972459048] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8qfln; range_end:; response_count:1; response_revision:762; }","duration":"332.234047ms","start":"2026-08-16T12:52:52.015676Z","end":"2026-08-16T12:52:52.34791Z","steps":["trace[972459048] 'agreement among raft nodes before linearized reading' (duration: 331.640253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:52:52.347938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:52:52.015659Z","time spent":"332.275017ms","remote":"127.0.0.1:34528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8qfln\" "} {"level":"warn","ts":"2026-08-16T12:53:06.965575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104428572715656,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-16T12:53:07.14365Z","caller":"traceutil/trace.go:171","msg":"trace[1621269960] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"678.636081ms","start":"2026-08-16T12:53:06.46499Z","end":"2026-08-16T12:53:07.143626Z","steps":["trace[1621269960] 'read index received' (duration: 678.58859ms)","trace[1621269960] 'applied index is now lower than readState.Index' (duration: 45.941µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:53:07.143764Z","caller":"traceutil/trace.go:171","msg":"trace[1826149025] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"760.984403ms","start":"2026-08-16T12:53:06.38275Z","end":"2026-08-16T12:53:07.143734Z","steps":["trace[1826149025] 'process raft request' (duration: 760.749477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:07.143932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:06.382734Z","time spent":"761.100145ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:53:07.144044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.00133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-8zsjt\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-08-16T12:53:07.144207Z","caller":"traceutil/trace.go:171","msg":"trace[2128663458] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-8zsjt; range_end:; response_count:1; response_revision:899; }","duration":"679.206485ms","start":"2026-08-16T12:53:06.46494Z","end":"2026-08-16T12:53:07.144147Z","steps":["trace[2128663458] 'agreement among raft nodes before linearized reading' (duration: 678.799585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:07.144285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:06.464922Z","time spent":"679.322967ms","remote":"127.0.0.1:34528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5699,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-8zsjt\" "} {"level":"warn","ts":"2026-08-16T12:53:07.190218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.285333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:07.190282Z","caller":"traceutil/trace.go:171","msg":"trace[420648906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"494.397325ms","start":"2026-08-16T12:53:06.695871Z","end":"2026-08-16T12:53:07.190268Z","steps":["trace[420648906] 'agreement among raft nodes before linearized reading' (duration: 494.272712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:07.190339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:06.695854Z","time spent":"494.474058ms","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T12:53:07.190402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.063757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:53:07.190437Z","caller":"traceutil/trace.go:171","msg":"trace[1629591124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"692.106417ms","start":"2026-08-16T12:53:06.498319Z","end":"2026-08-16T12:53:07.190425Z","steps":["trace[1629591124] 'agreement among raft nodes before linearized reading' (duration: 692.048556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:07.190466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:06.49831Z","time spent":"692.151739ms","remote":"127.0.0.1:34592","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-08-16T12:53:07.190318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.261688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-16T12:53:07.190636Z","caller":"traceutil/trace.go:171","msg":"trace[1639307972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:900; }","duration":"706.624868ms","start":"2026-08-16T12:53:06.484004Z","end":"2026-08-16T12:53:07.190629Z","steps":["trace[1639307972] 'agreement among raft nodes before linearized reading' (duration: 706.233248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:07.190661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:06.483986Z","time spent":"706.66852ms","remote":"127.0.0.1:34592","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-08-16T12:53:07.190706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.615636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-16T12:53:07.190747Z","caller":"traceutil/trace.go:171","msg":"trace[1378547279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:900; }","duration":"708.674358ms","start":"2026-08-16T12:53:06.482062Z","end":"2026-08-16T12:53:07.190736Z","steps":["trace[1378547279] 'agreement among raft nodes before linearized reading' (duration: 708.532024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:07.190772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:06.482053Z","time spent":"708.712098ms","remote":"127.0.0.1:34592","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-08-16T12:53:07.550911Z","caller":"traceutil/trace.go:171","msg":"trace[562961156] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"360.775555ms","start":"2026-08-16T12:53:07.190114Z","end":"2026-08-16T12:53:07.55089Z","steps":["trace[562961156] 'read index received' (duration: 360.631151ms)","trace[562961156] 'applied index is now lower than readState.Index' (duration: 143.373µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:53:07.551182Z","caller":"traceutil/trace.go:171","msg":"trace[1340890889] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"398.430335ms","start":"2026-08-16T12:53:07.152742Z","end":"2026-08-16T12:53:07.551172Z","steps":["trace[1340890889] 'process raft request' (duration: 398.016115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:07.551268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:07.152723Z","time spent":"398.483606ms","remote":"127.0.0.1:34528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:53:07.551508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.042477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-16T12:53:07.551597Z","caller":"traceutil/trace.go:171","msg":"trace[186455391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"396.18ms","start":"2026-08-16T12:53:07.155399Z","end":"2026-08-16T12:53:07.551579Z","steps":["trace[186455391] 'agreement among raft nodes before linearized reading' (duration: 395.914954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:07.551714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:07.155384Z","time spent":"396.317125ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-16T12:53:08.357919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.632902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:53:08.358058Z","caller":"traceutil/trace.go:171","msg":"trace[1941564593] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:935; }","duration":"807.069806ms","start":"2026-08-16T12:53:07.550977Z","end":"2026-08-16T12:53:08.358047Z","steps":["trace[1941564593] 'read index received' (duration: 56.317401ms)","trace[1941564593] 'applied index is now lower than readState.Index' (duration: 750.751735ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:53:08.358075Z","caller":"traceutil/trace.go:171","msg":"trace[1474208014] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"1.164118431s","start":"2026-08-16T12:53:07.193942Z","end":"2026-08-16T12:53:08.35806Z","steps":["trace[1474208014] 'process raft request' (duration: 1.164046769s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:08.358113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165898514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:08.35813Z","caller":"traceutil/trace.go:171","msg":"trace[1803740339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"1.165916144s","start":"2026-08-16T12:53:07.192208Z","end":"2026-08-16T12:53:08.358124Z","steps":["trace[1803740339] 'agreement among raft nodes before linearized reading' (duration: 1.165872533s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:08.358134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:07.193917Z","time spent":"1.164187792s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:53:08.358147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:07.19217Z","time spent":"1.165974066s","remote":"127.0.0.1:34360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-16T12:53:08.358114Z","caller":"traceutil/trace.go:171","msg":"trace[1539707929] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"1.163516236s","start":"2026-08-16T12:53:07.194572Z","end":"2026-08-16T12:53:08.358089Z","steps":["trace[1539707929] 'process raft request' (duration: 1.163447975s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:08.358247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:07.194557Z","time spent":"1.163619538s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:53:08.358268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.467111ms","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-08-16T12:53:08.358285Z","caller":"traceutil/trace.go:171","msg":"trace[1695677839] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:904; }","duration":"911.517893ms","start":"2026-08-16T12:53:07.446762Z","end":"2026-08-16T12:53:08.35828Z","steps":["trace[1695677839] 'agreement among raft nodes before linearized reading' (duration: 911.484102ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:08.35829Z","caller":"traceutil/trace.go:171","msg":"trace[720583148] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"1.164391557s","start":"2026-08-16T12:53:07.193889Z","end":"2026-08-16T12:53:08.358281Z","steps":["trace[720583148] 'process raft request' (duration: 413.324624ms)","trace[720583148] 'compare' (duration: 750.481389ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:08.358303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:07.446741Z","time spent":"911.556203ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:53:08.358332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:07.19387Z","time spent":"1.164440139s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:53:08.35863Z","caller":"traceutil/trace.go:171","msg":"trace[1040186319] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"799.677618ms","start":"2026-08-16T12:53:07.558922Z","end":"2026-08-16T12:53:08.3586Z","steps":["trace[1040186319] 'process raft request' (duration: 799.3512ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:08.358673Z","caller":"traceutil/trace.go:171","msg":"trace[1519065888] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"799.73181ms","start":"2026-08-16T12:53:07.55893Z","end":"2026-08-16T12:53:08.358662Z","steps":["trace[1519065888] 'process raft request' (duration: 799.586176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:08.358729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:07.558913Z","time spent":"799.785701ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:53:08.358787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:07.558906Z","time spent":"799.793271ms","remote":"127.0.0.1:34516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:53:08.358961Z","caller":"traceutil/trace.go:171","msg":"trace[901966002] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"799.894233ms","start":"2026-08-16T12:53:07.55904Z","end":"2026-08-16T12:53:08.358934Z","steps":["trace[901966002] 'process raft request' (duration: 799.530515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:08.359019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:07.559036Z","time spent":"799.952775ms","remote":"127.0.0.1:34604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1628,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:53:08.359067Z","caller":"traceutil/trace.go:171","msg":"trace[638821294] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"799.956145ms","start":"2026-08-16T12:53:07.559103Z","end":"2026-08-16T12:53:08.359059Z","steps":["trace[638821294] 'process raft request' (duration: 799.765551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:08.359106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:07.559096Z","time spent":"799.988796ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:53:16.699699Z","caller":"traceutil/trace.go:171","msg":"trace[243220625] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"233.062976ms","start":"2026-08-16T12:53:16.466608Z","end":"2026-08-16T12:53:16.699671Z","steps":["trace[243220625] 'read index received' (duration: 232.870561ms)","trace[243220625] 'applied index is now lower than readState.Index' (duration: 191.225µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:53:16.699858Z","caller":"traceutil/trace.go:171","msg":"trace[2074878662] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"257.176749ms","start":"2026-08-16T12:53:16.442667Z","end":"2026-08-16T12:53:16.699844Z","steps":["trace[2074878662] 'process raft request' (duration: 256.80795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:16.700051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.179978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-16T12:53:16.700123Z","caller":"traceutil/trace.go:171","msg":"trace[2075818229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:938; }","duration":"233.287561ms","start":"2026-08-16T12:53:16.466819Z","end":"2026-08-16T12:53:16.700106Z","steps":["trace[2075818229] 'agreement among raft nodes before linearized reading' (duration: 233.114297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:16.700218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.599229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-16T12:53:16.700289Z","caller":"traceutil/trace.go:171","msg":"trace[1706304326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:938; }","duration":"233.706791ms","start":"2026-08-16T12:53:16.466569Z","end":"2026-08-16T12:53:16.700276Z","steps":["trace[1706304326] 'agreement among raft nodes before linearized reading' (duration: 233.558228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:16.700295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.889172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:53:16.700387Z","caller":"traceutil/trace.go:171","msg":"trace[1690507904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:938; }","duration":"233.053716ms","start":"2026-08-16T12:53:16.467317Z","end":"2026-08-16T12:53:16.70037Z","steps":["trace[1690507904] 'agreement among raft nodes before linearized reading' (duration: 232.81378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:17.141442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.90185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:53:17.14163Z","caller":"traceutil/trace.go:171","msg":"trace[50205754] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"438.001872ms","start":"2026-08-16T12:53:16.703604Z","end":"2026-08-16T12:53:17.141605Z","steps":["trace[50205754] 'process raft request' (duration: 190.845216ms)","trace[50205754] 'compare' (duration: 246.757327ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:17.14172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:16.703586Z","time spent":"438.089685ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:53:17.653631Z","caller":"traceutil/trace.go:171","msg":"trace[476085633] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"949.195264ms","start":"2026-08-16T12:53:16.704408Z","end":"2026-08-16T12:53:17.653603Z","steps":["trace[476085633] 'process raft request' (duration: 948.930506ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:17.653661Z","caller":"traceutil/trace.go:171","msg":"trace[1889838470] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:974; }","duration":"884.652503ms","start":"2026-08-16T12:53:16.76898Z","end":"2026-08-16T12:53:17.653632Z","steps":["trace[1889838470] 'read index received' (duration: 125.446954ms)","trace[1889838470] 'applied index is now lower than readState.Index' (duration: 759.204119ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:17.653815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:16.7044Z","time spent":"949.306286ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:53:17.653838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.831857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:17.653915Z","caller":"traceutil/trace.go:171","msg":"trace[372432799] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:941; }","duration":"884.962471ms","start":"2026-08-16T12:53:16.768941Z","end":"2026-08-16T12:53:17.653904Z","steps":["trace[372432799] 'agreement among raft nodes before linearized reading' (duration: 884.786346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:17.65396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:16.768925Z","time spent":"885.025072ms","remote":"127.0.0.1:34614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-16T12:53:17.653702Z","caller":"traceutil/trace.go:171","msg":"trace[2055932648] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"948.684711ms","start":"2026-08-16T12:53:16.704985Z","end":"2026-08-16T12:53:17.653669Z","steps":["trace[2055932648] 'process raft request' (duration: 948.538538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:17.654427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:16.70496Z","time spent":"949.329977ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:53:17.658829Z","caller":"traceutil/trace.go:171","msg":"trace[1079307104] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"428.205234ms","start":"2026-08-16T12:53:17.230604Z","end":"2026-08-16T12:53:17.658809Z","steps":["trace[1079307104] 'process raft request' (duration: 428.074451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:17.658974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:17.230583Z","time spent":"428.306317ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:53:17.661385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.561649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-16T12:53:17.661411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.261976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T12:53:17.661427Z","caller":"traceutil/trace.go:171","msg":"trace[280945558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:942; }","duration":"482.642121ms","start":"2026-08-16T12:53:17.178775Z","end":"2026-08-16T12:53:17.661418Z","steps":["trace[280945558] 'agreement among raft nodes before linearized reading' (duration: 482.507978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:17.661457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:17.178756Z","time spent":"482.695683ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-16T12:53:17.661456Z","caller":"traceutil/trace.go:171","msg":"trace[83990681] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:942; }","duration":"306.343077ms","start":"2026-08-16T12:53:17.3551Z","end":"2026-08-16T12:53:17.661443Z","steps":["trace[83990681] 'agreement among raft nodes before linearized reading' (duration: 306.207124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:17.661487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:17.355079Z","time spent":"306.399949ms","remote":"127.0.0.1:34848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":95,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-08-16T12:53:19.847623Z","caller":"traceutil/trace.go:171","msg":"trace[1482147747] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"184.957992ms","start":"2026-08-16T12:53:19.662641Z","end":"2026-08-16T12:53:19.847599Z","steps":["trace[1482147747] 'process raft request' (duration: 184.740087ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:19.847659Z","caller":"traceutil/trace.go:171","msg":"trace[1457728663] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"184.897071ms","start":"2026-08-16T12:53:19.662732Z","end":"2026-08-16T12:53:19.847629Z","steps":["trace[1457728663] 'process raft request' (duration: 184.786048ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:19.847578Z","caller":"traceutil/trace.go:171","msg":"trace[1436983299] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:982; }","duration":"174.222962ms","start":"2026-08-16T12:53:19.673334Z","end":"2026-08-16T12:53:19.847557Z","steps":["trace[1436983299] 'read index received' (duration: 5.43317ms)","trace[1436983299] 'applied index is now lower than readState.Index' (duration: 168.788862ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:19.84787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.490249ms","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-08-16T12:53:19.847922Z","caller":"traceutil/trace.go:171","msg":"trace[86770034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"174.577731ms","start":"2026-08-16T12:53:19.673332Z","end":"2026-08-16T12:53:19.84791Z","steps":["trace[86770034] 'agreement among raft nodes before linearized reading' (duration: 174.388956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:39.478553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.885972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:39.478653Z","caller":"traceutil/trace.go:171","msg":"trace[792811189] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1015; }","duration":"195.051496ms","start":"2026-08-16T12:53:39.283584Z","end":"2026-08-16T12:53:39.478635Z","steps":["trace[792811189] 'count revisions from in-memory index tree' (duration: 194.78769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:39.478893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.298696ms","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-08-16T12:53:39.478986Z","caller":"traceutil/trace.go:171","msg":"trace[1008136665] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1015; }","duration":"160.393278ms","start":"2026-08-16T12:53:39.318579Z","end":"2026-08-16T12:53:39.478972Z","steps":["trace[1008136665] 'range keys from in-memory index tree' (duration: 160.286816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:40.23605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.402081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:53:40.236207Z","caller":"traceutil/trace.go:171","msg":"trace[1213824671] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"261.051932ms","start":"2026-08-16T12:53:39.975138Z","end":"2026-08-16T12:53:40.23619Z","steps":["trace[1213824671] 'process raft request' (duration: 260.996051ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:40.236462Z","caller":"traceutil/trace.go:171","msg":"trace[1240435444] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"261.385741ms","start":"2026-08-16T12:53:39.975068Z","end":"2026-08-16T12:53:40.236453Z","steps":["trace[1240435444] 'process raft request' (duration: 126.500729ms)","trace[1240435444] 'compare' (duration: 134.295617ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:53:49.566636Z","caller":"traceutil/trace.go:171","msg":"trace[764489018] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"214.084117ms","start":"2026-08-16T12:53:49.352494Z","end":"2026-08-16T12:53:49.566578Z","steps":["trace[764489018] 'read index received' (duration: 213.874092ms)","trace[764489018] 'applied index is now lower than readState.Index' (duration: 208.265µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:53:49.566762Z","caller":"traceutil/trace.go:171","msg":"trace[558173036] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"283.197909ms","start":"2026-08-16T12:53:49.283545Z","end":"2026-08-16T12:53:49.566743Z","steps":["trace[558173036] 'process raft request' (duration: 282.87736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:49.566792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.274892ms","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-08-16T12:53:49.566827Z","caller":"traceutil/trace.go:171","msg":"trace[299731648] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1048; }","duration":"214.385005ms","start":"2026-08-16T12:53:49.352432Z","end":"2026-08-16T12:53:49.566817Z","steps":["trace[299731648] 'agreement among raft nodes before linearized reading' (duration: 214.294042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:52.510558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104428572716058,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:53:53.010734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104428572716058,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:53:53.511083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104428572716058,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:53:53.534887Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.528648677s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-16T12:53:54.219803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.319505812s","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-08-16T12:53:54.219876Z","caller":"traceutil/trace.go:171","msg":"trace[360642313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1050; }","duration":"3.319618866s","start":"2026-08-16T12:53:50.900231Z","end":"2026-08-16T12:53:54.21985Z","steps":["trace[360642313] 'range keys from in-memory index tree' (duration: 3.319355558s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.219906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:50.900212Z","time spent":"3.319686627s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-16T12:53:54.219924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.944822775s","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-08-16T12:53:54.219973Z","caller":"traceutil/trace.go:171","msg":"trace[1269620626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1050; }","duration":"3.944932468s","start":"2026-08-16T12:53:50.275028Z","end":"2026-08-16T12:53:54.21996Z","steps":["trace[1269620626] 'range keys from in-memory index tree' (duration: 3.94464467s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.219998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:50.275Z","time spent":"3.94499214s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-16T12:53:54.220244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.908962268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T12:53:54.220264Z","caller":"traceutil/trace.go:171","msg":"trace[664616095] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1050; }","duration":"3.90902145s","start":"2026-08-16T12:53:50.311236Z","end":"2026-08-16T12:53:54.220258Z","steps":["trace[664616095] 'count revisions from in-memory index tree' (duration: 3.908869246s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.220281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:50.311214Z","time spent":"3.90906172s","remote":"127.0.0.1:34604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:53:54.220443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.648777444s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-16T12:53:54.220467Z","caller":"traceutil/trace.go:171","msg":"trace[884282378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1050; }","duration":"2.648842616s","start":"2026-08-16T12:53:51.571617Z","end":"2026-08-16T12:53:54.22046Z","steps":["trace[884282378] 'range keys from in-memory index tree' (duration: 2.648671401s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.22048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.522467412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:53:54.220488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:51.571594Z","time spent":"2.648889426s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-16T12:53:54.2205Z","caller":"traceutil/trace.go:171","msg":"trace[1618876318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1050; }","duration":"3.522525914s","start":"2026-08-16T12:53:50.697969Z","end":"2026-08-16T12:53:54.220495Z","steps":["trace[1618876318] 'range keys from in-memory index tree' (duration: 3.52238655s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.220517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:50.697951Z","time spent":"3.522562234s","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T12:53:54.220637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.651075712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-16T12:53:54.220672Z","caller":"traceutil/trace.go:171","msg":"trace[1284079521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1050; }","duration":"3.651139713s","start":"2026-08-16T12:53:50.569524Z","end":"2026-08-16T12:53:54.220664Z","steps":["trace[1284079521] 'range keys from in-memory index tree' (duration: 3.650901226s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.220689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:50.569508Z","time spent":"3.651176114s","remote":"127.0.0.1:34592","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-08-16T12:53:54.221193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.080721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:53:54.221299Z","caller":"traceutil/trace.go:171","msg":"trace[1198021637] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"672.207606ms","start":"2026-08-16T12:53:53.549082Z","end":"2026-08-16T12:53:54.22129Z","steps":["trace[1198021637] 'process raft request' (duration: 672.156684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.221337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:53.549064Z","time spent":"672.253706ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:53:54.221397Z","caller":"traceutil/trace.go:171","msg":"trace[1002607731] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"2.215532466s","start":"2026-08-16T12:53:52.005854Z","end":"2026-08-16T12:53:54.221387Z","steps":["trace[1002607731] 'process raft request' (duration: 1.529212689s)","trace[1002607731] 'compare' (duration: 685.368524ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:53:54.221432Z","caller":"traceutil/trace.go:171","msg":"trace[843653150] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1093; }","duration":"2.211924529s","start":"2026-08-16T12:53:52.009483Z","end":"2026-08-16T12:53:54.221407Z","steps":["trace[843653150] 'read index received' (duration: 1.525526411s)","trace[843653150] 'applied index is now lower than readState.Index' (duration: 686.395168ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:54.221486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.00583Z","time spent":"2.215614938s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:53:54.221533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.212059013s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:53:54.221588Z","caller":"traceutil/trace.go:171","msg":"trace[1589879926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1052; }","duration":"2.212154905s","start":"2026-08-16T12:53:52.009426Z","end":"2026-08-16T12:53:54.221581Z","steps":["trace[1589879926] 'agreement among raft nodes before linearized reading' (duration: 2.212057283s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.221607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.009402Z","time spent":"2.212200246s","remote":"127.0.0.1:34592","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-08-16T12:53:54.221725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522984458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:54.221764Z","caller":"traceutil/trace.go:171","msg":"trace[580020883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1052; }","duration":"1.523071161s","start":"2026-08-16T12:53:52.698686Z","end":"2026-08-16T12:53:54.221757Z","steps":["trace[580020883] 'agreement among raft nodes before linearized reading' (duration: 1.522987789s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.221775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600248667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-16T12:53:54.221787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.698676Z","time spent":"1.523103941s","remote":"127.0.0.1:34360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-16T12:53:54.221806Z","caller":"traceutil/trace.go:171","msg":"trace[1889558278] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1052; }","duration":"1.600317929s","start":"2026-08-16T12:53:52.62148Z","end":"2026-08-16T12:53:54.221798Z","steps":["trace[1889558278] 'agreement among raft nodes before linearized reading' (duration: 1.600268027s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.221836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.621458Z","time spent":"1.60037248s","remote":"127.0.0.1:34756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:53:54.221724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.173198893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-16T12:53:54.221948Z","caller":"traceutil/trace.go:171","msg":"trace[634905812] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1052; }","duration":"2.173464489s","start":"2026-08-16T12:53:52.048475Z","end":"2026-08-16T12:53:54.22194Z","steps":["trace[634905812] 'agreement among raft nodes before linearized reading' (duration: 2.173065879s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:54.222095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.048464Z","time spent":"2.173622023s","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"info","ts":"2026-08-16T12:53:57.820861Z","caller":"traceutil/trace.go:171","msg":"trace[1203176458] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1066; }","duration":"502.111622ms","start":"2026-08-16T12:53:57.318723Z","end":"2026-08-16T12:53:57.820834Z","steps":["trace[1203176458] 'process raft request' (duration: 502.02893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:57.820991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:57.31871Z","time spent":"502.224715ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:53:57.823104Z","caller":"traceutil/trace.go:171","msg":"trace[483212661] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"253.48068ms","start":"2026-08-16T12:53:57.569607Z","end":"2026-08-16T12:53:57.823088Z","steps":["trace[483212661] 'read index received' (duration: 251.230195ms)","trace[483212661] 'applied index is now lower than readState.Index' (duration: 2.249345ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:57.823243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.824635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-16T12:53:57.823243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.493885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:57.823292Z","caller":"traceutil/trace.go:171","msg":"trace[2043801595] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1066; }","duration":"245.921218ms","start":"2026-08-16T12:53:57.577359Z","end":"2026-08-16T12:53:57.823281Z","steps":["trace[2043801595] 'agreement among raft nodes before linearized reading' (duration: 245.826065ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:57.823303Z","caller":"traceutil/trace.go:171","msg":"trace[213209823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"125.596478ms","start":"2026-08-16T12:53:57.697694Z","end":"2026-08-16T12:53:57.823291Z","steps":["trace[213209823] 'agreement among raft nodes before linearized reading' (duration: 125.501106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:57.823345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.713276ms","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-08-16T12:53:57.82342Z","caller":"traceutil/trace.go:171","msg":"trace[1330858162] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1066; }","duration":"253.85023ms","start":"2026-08-16T12:53:57.569552Z","end":"2026-08-16T12:53:57.823403Z","steps":["trace[1330858162] 'agreement among raft nodes before linearized reading' (duration: 253.688436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:00.763036Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104428572716208,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-16T12:54:01.214057Z","caller":"traceutil/trace.go:171","msg":"trace[1015448256] linearizableReadLoop","detail":"{readStateIndex:1172; appliedIndex:1171; }","duration":"951.56402ms","start":"2026-08-16T12:54:00.26247Z","end":"2026-08-16T12:54:01.214034Z","steps":["trace[1015448256] 'read index received' (duration: 951.394036ms)","trace[1015448256] 'applied index is now lower than readState.Index' (duration: 168.974µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:01.21409Z","caller":"traceutil/trace.go:171","msg":"trace[1431771644] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"974.434382ms","start":"2026-08-16T12:54:00.239627Z","end":"2026-08-16T12:54:01.214061Z","steps":["trace[1431771644] 'process raft request' (duration: 974.245107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:01.214211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:00.239604Z","time spent":"974.546145ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:54:01.214298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.817817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-16T12:54:01.214333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.565617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:54:01.21434Z","caller":"traceutil/trace.go:171","msg":"trace[417997266] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1125; }","duration":"951.914499ms","start":"2026-08-16T12:54:00.262415Z","end":"2026-08-16T12:54:01.21433Z","steps":["trace[417997266] 'agreement among raft nodes before linearized reading' (duration: 951.762145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:01.214379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.897458ms","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":"warn","ts":"2026-08-16T12:54:01.214382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:00.262394Z","time spent":"951.97973ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-16T12:54:01.214399Z","caller":"traceutil/trace.go:171","msg":"trace[1578343547] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1125; }","duration":"420.96266ms","start":"2026-08-16T12:54:00.793431Z","end":"2026-08-16T12:54:01.214394Z","steps":["trace[1578343547] 'agreement among raft nodes before linearized reading' (duration: 420.934269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:01.214419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:00.793407Z","time spent":"421.006911ms","remote":"127.0.0.1:34566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:54:01.214419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.551ms","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-08-16T12:54:01.21448Z","caller":"traceutil/trace.go:171","msg":"trace[109427217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1125; }","duration":"951.663462ms","start":"2026-08-16T12:54:00.262802Z","end":"2026-08-16T12:54:01.214466Z","steps":["trace[109427217] 'agreement among raft nodes before linearized reading' (duration: 951.475518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:01.214511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:00.262783Z","time spent":"951.722424ms","remote":"127.0.0.1:34592","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-08-16T12:54:01.214567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.308219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:54:01.214353Z","caller":"traceutil/trace.go:171","msg":"trace[273327786] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1125; }","duration":"742.622427ms","start":"2026-08-16T12:54:00.471724Z","end":"2026-08-16T12:54:01.214346Z","steps":["trace[273327786] 'agreement among raft nodes before linearized reading' (duration: 742.577426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:01.214618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:00.471705Z","time spent":"742.895835ms","remote":"127.0.0.1:54598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:54:01.214642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.553944ms","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-08-16T12:54:01.214683Z","caller":"traceutil/trace.go:171","msg":"trace[2078448372] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1125; }","duration":"590.656036ms","start":"2026-08-16T12:54:00.624023Z","end":"2026-08-16T12:54:01.214679Z","steps":["trace[2078448372] 'agreement among raft nodes before linearized reading' (duration: 590.572564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:01.214654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.777172ms","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-08-16T12:54:01.214699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:00.624004Z","time spent":"590.690578ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:54:01.21474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.658389ms","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-08-16T12:54:01.214763Z","caller":"traceutil/trace.go:171","msg":"trace[1194305950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1125; }","duration":"945.702339ms","start":"2026-08-16T12:54:00.269056Z","end":"2026-08-16T12:54:01.214759Z","steps":["trace[1194305950] 'agreement among raft nodes before linearized reading' (duration: 945.642228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:01.214778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:00.269046Z","time spent":"945.72861ms","remote":"127.0.0.1:34592","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-08-16T12:54:01.214587Z","caller":"traceutil/trace.go:171","msg":"trace[728403563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1125; }","duration":"519.353949ms","start":"2026-08-16T12:54:00.695229Z","end":"2026-08-16T12:54:01.214583Z","steps":["trace[728403563] 'agreement among raft nodes before linearized reading' (duration: 519.325768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:01.214793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.898217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T12:54:01.214831Z","caller":"traceutil/trace.go:171","msg":"trace[1627719501] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1125; }","duration":"816.964878ms","start":"2026-08-16T12:54:00.397855Z","end":"2026-08-16T12:54:01.21482Z","steps":["trace[1627719501] 'agreement among raft nodes before linearized reading' (duration: 816.901796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:01.214873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.233463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-08-16T12:54:01.214881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:00.397841Z","time spent":"817.029011ms","remote":"127.0.0.1:34528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-08-16T12:54:01.214731Z","caller":"traceutil/trace.go:171","msg":"trace[1835774565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1125; }","duration":"943.875535ms","start":"2026-08-16T12:54:00.270834Z","end":"2026-08-16T12:54:01.21471Z","steps":["trace[1835774565] 'agreement among raft nodes before linearized reading' (duration: 943.689141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:01.214913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:00.270826Z","time spent":"944.083231ms","remote":"127.0.0.1:34592","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-08-16T12:54:01.214829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:00.695213Z","time spent":"519.606626ms","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-16T12:54:01.214893Z","caller":"traceutil/trace.go:171","msg":"trace[1169209644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1125; }","duration":"946.288944ms","start":"2026-08-16T12:54:00.2686Z","end":"2026-08-16T12:54:01.214889Z","steps":["trace[1169209644] 'agreement among raft nodes before linearized reading' (duration: 946.244862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:01.215019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:00.268582Z","time spent":"946.428047ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-16T12:54:11.449573Z","caller":"traceutil/trace.go:171","msg":"trace[1714758691] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1206; }","duration":"184.076224ms","start":"2026-08-16T12:54:11.265474Z","end":"2026-08-16T12:54:11.44955Z","steps":["trace[1714758691] 'read index received' (duration: 183.811058ms)","trace[1714758691] 'applied index is now lower than readState.Index' (duration: 264.096µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:11.449617Z","caller":"traceutil/trace.go:171","msg":"trace[1762417082] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"197.530809ms","start":"2026-08-16T12:54:11.252061Z","end":"2026-08-16T12:54:11.449592Z","steps":["trace[1762417082] 'process raft request' (duration: 197.233342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:11.449846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.35945ms","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-08-16T12:54:11.449891Z","caller":"traceutil/trace.go:171","msg":"trace[904017518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1158; }","duration":"184.446963ms","start":"2026-08-16T12:54:11.265433Z","end":"2026-08-16T12:54:11.44988Z","steps":["trace[904017518] 'agreement among raft nodes before linearized reading' (duration: 184.297739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:11.449895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.119337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-16T12:54:11.449928Z","caller":"traceutil/trace.go:171","msg":"trace[1185389765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1158; }","duration":"182.187149ms","start":"2026-08-16T12:54:11.26773Z","end":"2026-08-16T12:54:11.449917Z","steps":["trace[1185389765] 'agreement among raft nodes before linearized reading' (duration: 182.046995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:11.45006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.604ms","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-08-16T12:54:11.45008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.651355ms","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-08-16T12:54:11.450109Z","caller":"traceutil/trace.go:171","msg":"trace[312251073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1158; }","duration":"180.671631ms","start":"2026-08-16T12:54:11.269425Z","end":"2026-08-16T12:54:11.450097Z","steps":["trace[312251073] 'agreement among raft nodes before linearized reading' (duration: 180.566088ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:11.450112Z","caller":"traceutil/trace.go:171","msg":"trace[2036437066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1158; }","duration":"181.728067ms","start":"2026-08-16T12:54:11.268374Z","end":"2026-08-16T12:54:11.450102Z","steps":["trace[2036437066] 'agreement among raft nodes before linearized reading' (duration: 181.633845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:11.450256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.005985ms","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-08-16T12:54:11.450289Z","caller":"traceutil/trace.go:171","msg":"trace[85054149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1158; }","duration":"180.062047ms","start":"2026-08-16T12:54:11.270219Z","end":"2026-08-16T12:54:11.450281Z","steps":["trace[85054149] 'agreement among raft nodes before linearized reading' (duration: 179.983615ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:20.852153Z","caller":"traceutil/trace.go:171","msg":"trace[14784022] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1268; }","duration":"154.89613ms","start":"2026-08-16T12:54:20.697232Z","end":"2026-08-16T12:54:20.852128Z","steps":["trace[14784022] 'read index received' (duration: 154.646894ms)","trace[14784022] 'applied index is now lower than readState.Index' (duration: 248.236µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:20.852187Z","caller":"traceutil/trace.go:171","msg":"trace[728452902] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"533.027389ms","start":"2026-08-16T12:54:20.319127Z","end":"2026-08-16T12:54:20.852154Z","steps":["trace[728452902] 'process raft request' (duration: 532.846295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:20.852285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.033953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:54:20.852328Z","caller":"traceutil/trace.go:171","msg":"trace[864979545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"155.119006ms","start":"2026-08-16T12:54:20.697199Z","end":"2026-08-16T12:54:20.852318Z","steps":["trace[864979545] 'agreement among raft nodes before linearized reading' (duration: 155.020273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:20.852339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:20.319097Z","time spent":"533.169033ms","remote":"127.0.0.1:34440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:54:21.775292Z","caller":"traceutil/trace.go:171","msg":"trace[97044645] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"182.578587ms","start":"2026-08-16T12:54:21.592686Z","end":"2026-08-16T12:54:21.775264Z","steps":["trace[97044645] 'process raft request' (duration: 105.304766ms)","trace[97044645] 'compare' (duration: 77.036625ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:21.775566Z","caller":"traceutil/trace.go:171","msg":"trace[821063998] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"182.388553ms","start":"2026-08-16T12:54:21.593166Z","end":"2026-08-16T12:54:21.775554Z","steps":["trace[821063998] 'process raft request' (duration: 182.024914ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:21.776041Z","caller":"traceutil/trace.go:171","msg":"trace[410086250] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"180.861146ms","start":"2026-08-16T12:54:21.59516Z","end":"2026-08-16T12:54:21.776021Z","steps":["trace[410086250] 'process raft request' (duration: 180.072027ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:21.776087Z","caller":"traceutil/trace.go:171","msg":"trace[1356881199] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"171.264545ms","start":"2026-08-16T12:54:21.604807Z","end":"2026-08-16T12:54:21.776072Z","steps":["trace[1356881199] 'process raft request' (duration: 171.105661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:26.053813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104428572716456,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-16T12:54:26.375746Z","caller":"traceutil/trace.go:171","msg":"trace[1384275599] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1291; }","duration":"822.841321ms","start":"2026-08-16T12:54:25.552883Z","end":"2026-08-16T12:54:26.375724Z","steps":["trace[1384275599] 'read index received' (duration: 822.652086ms)","trace[1384275599] 'applied index is now lower than readState.Index' (duration: 188.345µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:26.375876Z","caller":"traceutil/trace.go:171","msg":"trace[1340844711] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"829.123111ms","start":"2026-08-16T12:54:25.546743Z","end":"2026-08-16T12:54:26.375866Z","steps":["trace[1340844711] 'process raft request' (duration: 828.854245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:26.375985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.546721Z","time spent":"829.185033ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:54:26.376102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.792458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:54:26.376176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.853679ms","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-08-16T12:54:26.376178Z","caller":"traceutil/trace.go:171","msg":"trace[563206336] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1240; }","duration":"785.91703ms","start":"2026-08-16T12:54:25.590241Z","end":"2026-08-16T12:54:26.376158Z","steps":["trace[563206336] 'agreement among raft nodes before linearized reading' (duration: 785.762006ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:26.37623Z","caller":"traceutil/trace.go:171","msg":"trace[1602628143] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1240; }","duration":"768.937681ms","start":"2026-08-16T12:54:25.607278Z","end":"2026-08-16T12:54:26.376216Z","steps":["trace[1602628143] 'agreement among raft nodes before linearized reading' (duration: 768.752427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:26.376246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.590225Z","time spent":"786.006473ms","remote":"127.0.0.1:54666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:54:26.376261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.375303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:54:26.376315Z","caller":"traceutil/trace.go:171","msg":"trace[770712202] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1240; }","duration":"823.464375ms","start":"2026-08-16T12:54:25.552834Z","end":"2026-08-16T12:54:26.376299Z","steps":["trace[770712202] 'agreement among raft nodes before linearized reading' (duration: 823.377713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:26.376351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.819024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-16T12:54:26.376382Z","caller":"traceutil/trace.go:171","msg":"trace[776079608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1240; }","duration":"527.885355ms","start":"2026-08-16T12:54:25.848488Z","end":"2026-08-16T12:54:26.376373Z","steps":["trace[776079608] 'agreement among raft nodes before linearized reading' (duration: 527.804783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:26.376381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.552816Z","time spent":"823.550567ms","remote":"127.0.0.1:54672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-16T12:54:26.376412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.848467Z","time spent":"527.938477ms","remote":"127.0.0.1:34592","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-08-16T12:54:26.376473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.86855ms","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-08-16T12:54:26.376511Z","caller":"traceutil/trace.go:171","msg":"trace[463860914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1240; }","duration":"773.942092ms","start":"2026-08-16T12:54:25.602559Z","end":"2026-08-16T12:54:26.376501Z","steps":["trace[463860914] 'agreement among raft nodes before linearized reading' (duration: 773.811559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:26.37654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.602539Z","time spent":"773.995393ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-16T12:54:26.37627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.607241Z","time spent":"769.017673ms","remote":"127.0.0.1:34592","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-08-16T12:54:26.376619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.360158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:54:26.376652Z","caller":"traceutil/trace.go:171","msg":"trace[1807864258] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1240; }","duration":"291.43734ms","start":"2026-08-16T12:54:26.085204Z","end":"2026-08-16T12:54:26.376642Z","steps":["trace[1807864258] 'agreement among raft nodes before linearized reading' (duration: 291.382958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:26.376162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.102887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-16T12:54:26.376698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.348903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:54:26.376741Z","caller":"traceutil/trace.go:171","msg":"trace[1259334657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1240; }","duration":"527.729342ms","start":"2026-08-16T12:54:25.848998Z","end":"2026-08-16T12:54:26.376727Z","steps":["trace[1259334657] 'agreement among raft nodes before linearized reading' (duration: 527.006844ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:26.376743Z","caller":"traceutil/trace.go:171","msg":"trace[1322879045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1240; }","duration":"527.405114ms","start":"2026-08-16T12:54:25.849327Z","end":"2026-08-16T12:54:26.376732Z","steps":["trace[1322879045] 'agreement among raft nodes before linearized reading' (duration: 527.295261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:26.376774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.848978Z","time spent":"527.786682ms","remote":"127.0.0.1:34592","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-08-16T12:54:26.376776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:25.849322Z","time spent":"527.447665ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-16T12:55:31.893853Z","caller":"traceutil/trace.go:171","msg":"trace[819031722] linearizableReadLoop","detail":"{readStateIndex:1631; appliedIndex:1630; }","duration":"196.539287ms","start":"2026-08-16T12:55:31.697299Z","end":"2026-08-16T12:55:31.893838Z","steps":["trace[819031722] 'read index received' (duration: 196.354493ms)","trace[819031722] 'applied index is now lower than readState.Index' (duration: 184.274µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:31.893939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.628249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:31.893955Z","caller":"traceutil/trace.go:171","msg":"trace[173423785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1566; }","duration":"196.683931ms","start":"2026-08-16T12:55:31.697266Z","end":"2026-08-16T12:55:31.89395Z","steps":["trace[173423785] 'agreement among raft nodes before linearized reading' (duration: 196.63783ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:31.893938Z","caller":"traceutil/trace.go:171","msg":"trace[628964238] transaction","detail":"{read_only:false; response_revision:1566; number_of_response:1; }","duration":"245.325724ms","start":"2026-08-16T12:55:31.648592Z","end":"2026-08-16T12:55:31.893918Z","steps":["trace[628964238] 'process raft request' (duration: 245.109519ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:52.821751Z","caller":"traceutil/trace.go:171","msg":"trace[163195850] transaction","detail":"{read_only:false; response_revision:1662; number_of_response:1; }","duration":"101.464366ms","start":"2026-08-16T12:55:52.720263Z","end":"2026-08-16T12:55:52.821728Z","steps":["trace[163195850] 'process raft request' (duration: 101.211189ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:53.345989Z","caller":"traceutil/trace.go:171","msg":"trace[554725409] transaction","detail":"{read_only:false; response_revision:1664; number_of_response:1; }","duration":"515.408743ms","start":"2026-08-16T12:55:52.830552Z","end":"2026-08-16T12:55:53.34596Z","steps":["trace[554725409] 'process raft request' (duration: 514.646404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:53.346156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:52.830538Z","time spent":"515.524336ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:53.346441Z","caller":"traceutil/trace.go:171","msg":"trace[320767223] transaction","detail":"{read_only:false; response_revision:1665; number_of_response:1; }","duration":"515.68507ms","start":"2026-08-16T12:55:52.83074Z","end":"2026-08-16T12:55:53.346425Z","steps":["trace[320767223] 'process raft request' (duration: 515.009794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:53.346492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:52.830734Z","time spent":"515.734681ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:53.346663Z","caller":"traceutil/trace.go:171","msg":"trace[374926268] transaction","detail":"{read_only:false; response_revision:1666; number_of_response:1; }","duration":"515.715151ms","start":"2026-08-16T12:55:52.83094Z","end":"2026-08-16T12:55:53.346655Z","steps":["trace[374926268] 'process raft request' (duration: 515.326442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:53.346713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:52.830934Z","time spent":"515.760082ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:55:53.347322Z","caller":"traceutil/trace.go:171","msg":"trace[910583823] linearizableReadLoop","detail":"{readStateIndex:1737; appliedIndex:1733; }","duration":"118.876215ms","start":"2026-08-16T12:55:53.228423Z","end":"2026-08-16T12:55:53.3473Z","steps":["trace[910583823] 'read index received' (duration: 116.895627ms)","trace[910583823] 'applied index is now lower than readState.Index' (duration: 1.979598ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:53.347481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.045969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-08-16T12:55:53.347508Z","caller":"traceutil/trace.go:171","msg":"trace[414808710] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1667; }","duration":"119.112891ms","start":"2026-08-16T12:55:53.228387Z","end":"2026-08-16T12:55:53.3475Z","steps":["trace[414808710] 'agreement among raft nodes before linearized reading' (duration: 118.989198ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:53.347675Z","caller":"traceutil/trace.go:171","msg":"trace[13151836] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"514.927872ms","start":"2026-08-16T12:55:52.832739Z","end":"2026-08-16T12:55:53.347667Z","steps":["trace[13151836] 'process raft request' (duration: 514.486251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:53.347756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:52.832722Z","time spent":"514.975293ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:55:55.200656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104428572717478,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-16T12:55:55.356381Z","caller":"traceutil/trace.go:171","msg":"trace[39729653] linearizableReadLoop","detail":"{readStateIndex:1740; appliedIndex:1739; }","duration":"656.32658ms","start":"2026-08-16T12:55:54.700039Z","end":"2026-08-16T12:55:55.356365Z","steps":["trace[39729653] 'read index received' (duration: 656.213127ms)","trace[39729653] 'applied index is now lower than readState.Index' (duration: 112.633µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:55.357001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.367722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:55:55.357055Z","caller":"traceutil/trace.go:171","msg":"trace[1533715287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1670; }","duration":"522.470995ms","start":"2026-08-16T12:55:54.834568Z","end":"2026-08-16T12:55:55.357039Z","steps":["trace[1533715287] 'agreement among raft nodes before linearized reading' (duration: 522.343371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:55.357088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:54.834541Z","time spent":"522.539076ms","remote":"127.0.0.1:34592","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-08-16T12:55:55.357141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.098409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:55.357176Z","caller":"traceutil/trace.go:171","msg":"trace[292642647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1670; }","duration":"657.149631ms","start":"2026-08-16T12:55:54.700018Z","end":"2026-08-16T12:55:55.357167Z","steps":["trace[292642647] 'agreement among raft nodes before linearized reading' (duration: 657.10534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:55.357199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:54.700011Z","time spent":"657.184031ms","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-16T12:55:55.356625Z","caller":"traceutil/trace.go:171","msg":"trace[1023471949] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"700.484855ms","start":"2026-08-16T12:55:54.656131Z","end":"2026-08-16T12:55:55.356616Z","steps":["trace[1023471949] 'process raft request' (duration: 700.111176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:55.357671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:54.656111Z","time spent":"701.465739ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:57:13.094013Z","caller":"traceutil/trace.go:171","msg":"trace[1911493101] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"104.606127ms","start":"2026-08-16T12:57:12.989373Z","end":"2026-08-16T12:57:13.093979Z","steps":["trace[1911493101] 'process raft request' (duration: 104.442983ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:57:40.69001Z","caller":"traceutil/trace.go:171","msg":"trace[230896378] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"229.831473ms","start":"2026-08-16T12:57:40.460137Z","end":"2026-08-16T12:57:40.689969Z","steps":["trace[230896378] 'process raft request' (duration: 229.632378ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:57:42.070635Z","caller":"traceutil/trace.go:171","msg":"trace[1113208123] linearizableReadLoop","detail":"{readStateIndex:2285; appliedIndex:2284; }","duration":"301.285149ms","start":"2026-08-16T12:57:41.769332Z","end":"2026-08-16T12:57:42.070617Z","steps":["trace[1113208123] 'read index received' (duration: 301.156546ms)","trace[1113208123] 'applied index is now lower than readState.Index' (duration: 127.683µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:57:42.070852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.501094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/\" range_end:\"/registry/services/endpoints/default0\" limit:500 ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-16T12:57:42.070881Z","caller":"traceutil/trace.go:171","msg":"trace[503017383] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"316.388834ms","start":"2026-08-16T12:57:41.754443Z","end":"2026-08-16T12:57:42.070831Z","steps":["trace[503017383] 'process raft request' (duration: 316.056276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:42.070949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.592891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/default/\" range_end:\"/registry/ingress/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:57:42.07099Z","caller":"traceutil/trace.go:171","msg":"trace[2098967622] range","detail":"{range_begin:/registry/ingress/default/; range_end:/registry/ingress/default0; response_count:0; response_revision:2192; }","duration":"273.670382ms","start":"2026-08-16T12:57:41.797308Z","end":"2026-08-16T12:57:42.070978Z","steps":["trace[2098967622] 'agreement among raft nodes before linearized reading' (duration: 273.60254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:42.071059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.384233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-16T12:57:42.071095Z","caller":"traceutil/trace.go:171","msg":"trace[943857420] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2192; }","duration":"198.439545ms","start":"2026-08-16T12:57:41.872647Z","end":"2026-08-16T12:57:42.071086Z","steps":["trace[943857420] 'agreement among raft nodes before linearized reading' (duration: 198.327132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:42.071101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:57:41.754425Z","time spent":"316.567978ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:57:42.071121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.584867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-16T12:57:42.071217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.107071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5641"} {"level":"info","ts":"2026-08-16T12:57:42.070933Z","caller":"traceutil/trace.go:171","msg":"trace[41807530] range","detail":"{range_begin:/registry/services/endpoints/default/; range_end:/registry/services/endpoints/default0; response_count:1; response_revision:2192; }","duration":"301.621398ms","start":"2026-08-16T12:57:41.769295Z","end":"2026-08-16T12:57:42.070917Z","steps":["trace[41807530] 'agreement among raft nodes before linearized reading' (duration: 301.484564ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:57:42.071245Z","caller":"traceutil/trace.go:171","msg":"trace[913817286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2192; }","duration":"229.759141ms","start":"2026-08-16T12:57:41.841468Z","end":"2026-08-16T12:57:42.071227Z","steps":["trace[913817286] 'agreement among raft nodes before linearized reading' (duration: 229.485394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:42.071306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:57:41.769283Z","time spent":"301.998106ms","remote":"127.0.0.1:34516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/\" range_end:\"/registry/services/endpoints/default0\" limit:500 "} {"level":"warn","ts":"2026-08-16T12:57:42.071434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.032671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-16T12:57:42.071447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.902279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-16T12:57:42.071468Z","caller":"traceutil/trace.go:171","msg":"trace[1757714383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2192; }","duration":"238.086522ms","start":"2026-08-16T12:57:41.833372Z","end":"2026-08-16T12:57:42.071459Z","steps":["trace[1757714383] 'agreement among raft nodes before linearized reading' (duration: 237.99047ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:57:42.071476Z","caller":"traceutil/trace.go:171","msg":"trace[134227186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2192; }","duration":"242.95205ms","start":"2026-08-16T12:57:41.828516Z","end":"2026-08-16T12:57:42.071468Z","steps":["trace[134227186] 'agreement among raft nodes before linearized reading' (duration: 242.867168ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:57:42.071246Z","caller":"traceutil/trace.go:171","msg":"trace[163124255] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:2192; }","duration":"271.172082ms","start":"2026-08-16T12:57:41.800066Z","end":"2026-08-16T12:57:42.071238Z","steps":["trace[163124255] 'agreement among raft nodes before linearized reading' (duration: 271.08468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:42.071583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.155365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-16T12:57:42.071638Z","caller":"traceutil/trace.go:171","msg":"trace[1698710972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2192; }","duration":"207.230396ms","start":"2026-08-16T12:57:41.864391Z","end":"2026-08-16T12:57:42.071622Z","steps":["trace[1698710972] 'agreement among raft nodes before linearized reading' (duration: 207.092023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:42.071615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.57138ms","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-08-16T12:57:42.071743Z","caller":"traceutil/trace.go:171","msg":"trace[1493731520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2192; }","duration":"254.738235ms","start":"2026-08-16T12:57:41.816984Z","end":"2026-08-16T12:57:42.071722Z","steps":["trace[1493731520] 'agreement among raft nodes before linearized reading' (duration: 254.152091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:57.198888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104428572720007,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:57:57.424613Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.08776921s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:57:57.424899Z","caller":"traceutil/trace.go:171","msg":"trace[899149601] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"1.0881768s","start":"2026-08-16T12:57:56.336705Z","end":"2026-08-16T12:57:57.424881Z","steps":["trace[899149601] 'process raft request' (duration: 1.088030986s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:57.425016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:57:56.336678Z","time spent":"1.088270592s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:57:57.428329Z","caller":"traceutil/trace.go:171","msg":"trace[1987751506] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"1.090937275s","start":"2026-08-16T12:57:56.337375Z","end":"2026-08-16T12:57:57.428313Z","steps":["trace[1987751506] 'process raft request' (duration: 1.090833483s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:57.428415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:57:56.337364Z","time spent":"1.090996847s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:57:57.428554Z","caller":"traceutil/trace.go:171","msg":"trace[1474249750] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"1.09108634s","start":"2026-08-16T12:57:56.337451Z","end":"2026-08-16T12:57:57.428538Z","steps":["trace[1474249750] 'process raft request' (duration: 1.090840104s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:57.428622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:57:56.337436Z","time spent":"1.091148541s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:57:57.42862Z","caller":"traceutil/trace.go:171","msg":"trace[972324946] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"1.090665289s","start":"2026-08-16T12:57:56.33793Z","end":"2026-08-16T12:57:57.428595Z","steps":["trace[972324946] 'process raft request' (duration: 1.090566517s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:57.428769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:57:56.33792Z","time spent":"1.090761151s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:57:57.428872Z","caller":"traceutil/trace.go:171","msg":"trace[813901901] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"1.091359636s","start":"2026-08-16T12:57:56.337498Z","end":"2026-08-16T12:57:57.428858Z","steps":["trace[813901901] 'process raft request' (duration: 1.090946966s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:57.428904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:57:56.337494Z","time spent":"1.091391717s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:57:57.428962Z","caller":"traceutil/trace.go:171","msg":"trace[680330271] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"1.089341178s","start":"2026-08-16T12:57:56.339614Z","end":"2026-08-16T12:57:57.428955Z","steps":["trace[680330271] 'process raft request' (duration: 1.088922298s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:57:57.428969Z","caller":"traceutil/trace.go:171","msg":"trace[1984538580] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"1.090971237s","start":"2026-08-16T12:57:56.337986Z","end":"2026-08-16T12:57:57.428958Z","steps":["trace[1984538580] 'process raft request' (duration: 1.090530346s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:57.429026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:57:56.339606Z","time spent":"1.089388779s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:57:57.429041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:57:56.337982Z","time spent":"1.091016778s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:57:57.430526Z","caller":"traceutil/trace.go:171","msg":"trace[847931986] linearizableReadLoop","detail":"{readStateIndex:2359; appliedIndex:2352; }","duration":"731.884782ms","start":"2026-08-16T12:57:56.698625Z","end":"2026-08-16T12:57:57.43051Z","steps":["trace[847931986] 'read index received' (duration: 726.150623ms)","trace[847931986] 'applied index is now lower than readState.Index' (duration: 5.733359ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:57:57.430688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.069856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:57:57.430731Z","caller":"traceutil/trace.go:171","msg":"trace[1563212263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2263; }","duration":"732.165059ms","start":"2026-08-16T12:57:56.698557Z","end":"2026-08-16T12:57:57.430722Z","steps":["trace[1563212263] 'agreement among raft nodes before linearized reading' (duration: 732.100257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:57.430755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:57:56.698534Z","time spent":"732.21466ms","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T12:57:57.430771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.831506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-16T12:57:57.430811Z","caller":"traceutil/trace.go:171","msg":"trace[805974012] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2263; }","duration":"112.912987ms","start":"2026-08-16T12:57:57.317876Z","end":"2026-08-16T12:57:57.430789Z","steps":["trace[805974012] 'agreement among raft nodes before linearized reading' (duration: 112.810655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:57.431014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.055981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-08-16T12:57:57.431039Z","caller":"traceutil/trace.go:171","msg":"trace[730882993] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2263; }","duration":"113.122742ms","start":"2026-08-16T12:57:57.317908Z","end":"2026-08-16T12:57:57.431031Z","steps":["trace[730882993] 'agreement among raft nodes before linearized reading' (duration: 113.02414ms)"],"step_count":1}