{"level":"warn","ts":"2026-08-14T09:19:34.195309Z","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-14T09:19:34.19541Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.195:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.195:2380","--initial-cluster=instance=https://199.19.213.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.195: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-14T09:19:34.195494Z","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-14T09:19:34.195503Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.195:2380"]} {"level":"info","ts":"2026-08-14T09:19:34.195528Z","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-14T09:19:34.196278Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"]} {"level":"info","ts":"2026-08-14T09:19:34.196352Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.195:2380"],"listen-peer-urls":["https://199.19.213.195:2380"],"advertise-client-urls":["https://199.19.213.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.195: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-14T09:19:34.199463Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.894707ms"} {"level":"info","ts":"2026-08-14T09:19:34.207663Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2808ca5e53a916c4","cluster-id":"b7ef986abcd4d0b8"} {"level":"info","ts":"2026-08-14T09:19:34.207744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T09:19:34.207766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became follower at term 0"} {"level":"info","ts":"2026-08-14T09:19:34.207775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2808ca5e53a916c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T09:19:34.207784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became follower at term 1"} {"level":"info","ts":"2026-08-14T09:19:34.207824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=(2884778067810129604)"} {"level":"warn","ts":"2026-08-14T09:19:34.214416Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T09:19:34.216173Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T09:19:34.217405Z","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-14T09:19:34.222441Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2808ca5e53a916c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T09:19:34.22274Z","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-14T09:19:34.222788Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2808ca5e53a916c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T09:19:34.222891Z","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-14T09:19:34.222934Z","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-14T09:19:34.22396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=(2884778067810129604)"} {"level":"info","ts":"2026-08-14T09:19:34.224226Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b7ef986abcd4d0b8","local-member-id":"2808ca5e53a916c4","added-peer-id":"2808ca5e53a916c4","added-peer-peer-urls":["https://199.19.213.195:2380"]} {"level":"info","ts":"2026-08-14T09:19:34.229804Z","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-14T09:19:34.230182Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.195:2380"} {"level":"info","ts":"2026-08-14T09:19:34.230097Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2808ca5e53a916c4","initial-advertise-peer-urls":["https://199.19.213.195:2380"],"listen-peer-urls":["https://199.19.213.195:2380"],"advertise-client-urls":["https://199.19.213.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T09:19:34.230571Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T09:19:34.230247Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.195:2380"} {"level":"info","ts":"2026-08-14T09:19:35.208891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T09:19:35.208966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T09:19:35.209004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 received MsgPreVoteResp from 2808ca5e53a916c4 at term 1"} {"level":"info","ts":"2026-08-14T09:19:35.209026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became candidate at term 2"} {"level":"info","ts":"2026-08-14T09:19:35.20904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 received MsgVoteResp from 2808ca5e53a916c4 at term 2"} {"level":"info","ts":"2026-08-14T09:19:35.209058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became leader at term 2"} {"level":"info","ts":"2026-08-14T09:19:35.20908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2808ca5e53a916c4 elected leader 2808ca5e53a916c4 at term 2"} {"level":"info","ts":"2026-08-14T09:19:35.210527Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2808ca5e53a916c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.195:2379]}","request-path":"/0/members/2808ca5e53a916c4/attributes","cluster-id":"b7ef986abcd4d0b8","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T09:19:35.210568Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:19:35.210673Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:19:35.210785Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:19:35.211104Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T09:19:35.211162Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T09:19:35.211861Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b7ef986abcd4d0b8","local-member-id":"2808ca5e53a916c4","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:19:35.21201Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:19:35.212053Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:19:35.214409Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-14T09:19:35.21504Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.195:2379"} {"level":"info","ts":"2026-08-14T09:19:48.672022Z","caller":"traceutil/trace.go:171","msg":"trace[1984722449] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"173.675325ms","start":"2026-08-14T09:19:48.498319Z","end":"2026-08-14T09:19:48.671994Z","steps":["trace[1984722449] 'read index received' (duration: 173.454199ms)","trace[1984722449] 'applied index is now lower than readState.Index' (duration: 219.956µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:19:48.672148Z","caller":"traceutil/trace.go:171","msg":"trace[1968042684] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"442.481137ms","start":"2026-08-14T09:19:48.229615Z","end":"2026-08-14T09:19:48.672096Z","steps":["trace[1968042684] 'process raft request' (duration: 442.223101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:19:48.67216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.818938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-14T09:19:48.672395Z","caller":"traceutil/trace.go:171","msg":"trace[883045683] range","detail":"{range_begin:/registry/masterleases/199.19.213.195; range_end:; response_count:1; response_revision:360; }","duration":"174.104815ms","start":"2026-08-14T09:19:48.498275Z","end":"2026-08-14T09:19:48.67238Z","steps":["trace[883045683] 'agreement among raft nodes before linearized reading' (duration: 173.829788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:19:48.673562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:19:48.229598Z","time spent":"442.648441ms","remote":"127.0.0.1:52266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:19:52.916908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.019045802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:19:52.917034Z","caller":"traceutil/trace.go:171","msg":"trace[734103926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:361; }","duration":"4.019239767s","start":"2026-08-14T09:19:48.897771Z","end":"2026-08-14T09:19:52.91701Z","steps":["trace[734103926] 'range keys from in-memory index tree' (duration: 4.018924769s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:19:52.917083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:19:48.897746Z","time spent":"4.019321819s","remote":"127.0.0.1:52324","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-14T09:19:52.917329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.019301248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:19:52.917368Z","caller":"traceutil/trace.go:171","msg":"trace[2082105962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:361; }","duration":"4.01937741s","start":"2026-08-14T09:19:48.897976Z","end":"2026-08-14T09:19:52.917353Z","steps":["trace[2082105962] 'range keys from in-memory index tree' (duration: 4.019179585s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:19:52.917397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:19:48.897951Z","time spent":"4.019438192s","remote":"127.0.0.1:52324","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-14T09:19:52.917588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.722783583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-14T09:19:52.917667Z","caller":"traceutil/trace.go:171","msg":"trace[1957655656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:361; }","duration":"3.722907126s","start":"2026-08-14T09:19:49.194739Z","end":"2026-08-14T09:19:52.917647Z","steps":["trace[1957655656] 'range keys from in-memory index tree' (duration: 3.722627109s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:19:52.91771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:19:49.194703Z","time spent":"3.722996529s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T09:19:52.918148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.641723471s","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-14T09:19:52.918179Z","caller":"traceutil/trace.go:171","msg":"trace[1738903481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:361; }","duration":"3.641792473s","start":"2026-08-14T09:19:49.276377Z","end":"2026-08-14T09:19:52.91817Z","steps":["trace[1738903481] 'range keys from in-memory index tree' (duration: 3.641392872s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:19:52.918211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:19:49.276352Z","time spent":"3.641852035s","remote":"127.0.0.1:52324","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-14T09:19:52.919204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.19873243s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:19:52.919673Z","caller":"traceutil/trace.go:171","msg":"trace[482579520] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"2.042863209s","start":"2026-08-14T09:19:50.876794Z","end":"2026-08-14T09:19:52.919657Z","steps":["trace[482579520] 'read index received' (duration: 46.542µs)","trace[482579520] 'applied index is now lower than readState.Index' (duration: 2.042814827s)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:19:52.919818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.043019843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-08-14T09:19:52.919846Z","caller":"traceutil/trace.go:171","msg":"trace[838222019] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:362; }","duration":"2.043073724s","start":"2026-08-14T09:19:50.876763Z","end":"2026-08-14T09:19:52.919837Z","steps":["trace[838222019] 'agreement among raft nodes before linearized reading' (duration: 2.042966801s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:19:52.919875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:19:50.876746Z","time spent":"2.043121916s","remote":"127.0.0.1:52266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-08-14T09:19:52.920117Z","caller":"traceutil/trace.go:171","msg":"trace[70957797] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"2.736086418s","start":"2026-08-14T09:19:50.184004Z","end":"2026-08-14T09:19:52.92009Z","steps":["trace[70957797] 'process raft request' (duration: 535.915571ms)","trace[70957797] 'compare' (duration: 2.197816196s)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:19:52.920254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:19:50.183972Z","time spent":"2.736203711s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:20:47.318479Z","caller":"traceutil/trace.go:171","msg":"trace[1564010752] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"101.170302ms","start":"2026-08-14T09:20:47.217276Z","end":"2026-08-14T09:20:47.318446Z","steps":["trace[1564010752] 'process raft request' (duration: 101.117351ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:20:47.318998Z","caller":"traceutil/trace.go:171","msg":"trace[12312463] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"110.771211ms","start":"2026-08-14T09:20:47.208213Z","end":"2026-08-14T09:20:47.318985Z","steps":["trace[12312463] 'process raft request' (duration: 107.728892ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:20:48.771987Z","caller":"traceutil/trace.go:171","msg":"trace[616195976] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"164.674262ms","start":"2026-08-14T09:20:48.607283Z","end":"2026-08-14T09:20:48.771957Z","steps":["trace[616195976] 'process raft request' (duration: 155.062683ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:20:48.772115Z","caller":"traceutil/trace.go:171","msg":"trace[2090213865] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"139.399665ms","start":"2026-08-14T09:20:48.632693Z","end":"2026-08-14T09:20:48.772093Z","steps":["trace[2090213865] 'process raft request' (duration: 139.176159ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:20:48.772558Z","caller":"traceutil/trace.go:171","msg":"trace[1999406582] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"111.019418ms","start":"2026-08-14T09:20:48.661527Z","end":"2026-08-14T09:20:48.772546Z","steps":["trace[1999406582] 'process raft request' (duration: 110.69908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:49.906574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:49.428535Z","time spent":"478.032212ms","remote":"127.0.0.1:40668","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-14T09:20:49.907677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.33939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:20:49.907859Z","caller":"traceutil/trace.go:171","msg":"trace[1700135418] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"919.056733ms","start":"2026-08-14T09:20:48.988786Z","end":"2026-08-14T09:20:49.907843Z","steps":["trace[1700135418] 'process raft request' (duration: 297.454496ms)","trace[1700135418] 'compare' (duration: 621.142005ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:20:49.907968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:48.988759Z","time spent":"919.180666ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:20:50.288998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.885889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:20:50.289312Z","caller":"traceutil/trace.go:171","msg":"trace[449010328] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:685; }","duration":"1.080314668s","start":"2026-08-14T09:20:49.208972Z","end":"2026-08-14T09:20:50.289287Z","steps":["trace[449010328] 'read index received' (duration: 77.216308ms)","trace[449010328] 'applied index is now lower than readState.Index' (duration: 1.00309725s)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:20:50.289401Z","caller":"traceutil/trace.go:171","msg":"trace[402451876] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.254691113s","start":"2026-08-14T09:20:49.034699Z","end":"2026-08-14T09:20:50.28939Z","steps":["trace[402451876] 'process raft request' (duration: 1.104498567s)","trace[402451876] 'compare' (duration: 149.444357ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:20:50.289457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:49.03468Z","time spent":"1.254748704s","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:20:50.289463Z","caller":"traceutil/trace.go:171","msg":"trace[642691725] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.194244141s","start":"2026-08-14T09:20:49.095169Z","end":"2026-08-14T09:20:50.289413Z","steps":["trace[642691725] 'process raft request' (duration: 1.193970364s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:50.289581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:49.095141Z","time spent":"1.194380884s","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:20:50.289745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08077655s","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-14T09:20:50.289775Z","caller":"traceutil/trace.go:171","msg":"trace[1704961212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"1.080850092s","start":"2026-08-14T09:20:49.208915Z","end":"2026-08-14T09:20:50.289766Z","steps":["trace[1704961212] 'agreement among raft nodes before linearized reading' (duration: 1.08076142s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:50.289829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:49.208896Z","time spent":"1.080925863s","remote":"127.0.0.1:52324","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-14T09:20:50.789867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281590925,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:20:51.221742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.924578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:20:51.222477Z","caller":"traceutil/trace.go:171","msg":"trace[154613613] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.274421436s","start":"2026-08-14T09:20:49.947971Z","end":"2026-08-14T09:20:51.222393Z","steps":["trace[154613613] 'process raft request' (duration: 1.115705768s)","trace[154613613] 'compare' (duration: 157.750664ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:20:51.222756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:49.947945Z","time spent":"1.274620491s","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:20:51.224566Z","caller":"traceutil/trace.go:171","msg":"trace[1365940093] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:688; }","duration":"935.176883ms","start":"2026-08-14T09:20:50.289358Z","end":"2026-08-14T09:20:51.224535Z","steps":["trace[1365940093] 'read index received' (duration: 774.300239ms)","trace[1365940093] 'applied index is now lower than readState.Index' (duration: 160.875304ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:20:51.225102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593977117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:20:51.225176Z","caller":"traceutil/trace.go:171","msg":"trace[1432473452] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:660; }","duration":"1.59409823s","start":"2026-08-14T09:20:49.631059Z","end":"2026-08-14T09:20:51.225157Z","steps":["trace[1432473452] 'agreement among raft nodes before linearized reading' (duration: 1.593973887s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.225215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:49.631036Z","time spent":"1.594169843s","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-08-14T09:20:51.225319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.902971083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T09:20:51.225449Z","caller":"traceutil/trace.go:171","msg":"trace[1171493716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:660; }","duration":"1.903107947s","start":"2026-08-14T09:20:49.322264Z","end":"2026-08-14T09:20:51.225372Z","steps":["trace[1171493716] 'agreement among raft nodes before linearized reading' (duration: 1.902960423s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.225509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:49.322242Z","time spent":"1.903253631s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-14T09:20:51.225631Z","caller":"traceutil/trace.go:171","msg":"trace[1015393801] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"930.809199ms","start":"2026-08-14T09:20:50.294814Z","end":"2026-08-14T09:20:51.225623Z","steps":["trace[1015393801] 'process raft request' (duration: 929.643079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.225693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:50.294793Z","time spent":"930.86452ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:20:51.225782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.795120678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:20:51.225792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395591367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:20:51.225847Z","caller":"traceutil/trace.go:171","msg":"trace[1283543832] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:660; }","duration":"1.39570129s","start":"2026-08-14T09:20:49.830131Z","end":"2026-08-14T09:20:51.225832Z","steps":["trace[1283543832] 'agreement among raft nodes before linearized reading' (duration: 1.395606927s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:20:51.225841Z","caller":"traceutil/trace.go:171","msg":"trace[1971680865] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:660; }","duration":"1.795192549s","start":"2026-08-14T09:20:49.430631Z","end":"2026-08-14T09:20:51.225823Z","steps":["trace[1971680865] 'agreement among raft nodes before linearized reading' (duration: 1.795109197s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.225867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.817609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"warn","ts":"2026-08-14T09:20:51.225794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195042301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-08-14T09:20:51.225893Z","caller":"traceutil/trace.go:171","msg":"trace[840979349] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:660; }","duration":"1.195192955s","start":"2026-08-14T09:20:50.030697Z","end":"2026-08-14T09:20:51.22589Z","steps":["trace[840979349] 'agreement among raft nodes before linearized reading' (duration: 1.194971309s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:20:51.225899Z","caller":"traceutil/trace.go:171","msg":"trace[1847510338] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:660; }","duration":"995.878731ms","start":"2026-08-14T09:20:50.23001Z","end":"2026-08-14T09:20:51.225889Z","steps":["trace[1847510338] 'agreement among raft nodes before linearized reading' (duration: 995.81643ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:20:51.225515Z","caller":"traceutil/trace.go:171","msg":"trace[2142054926] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.12813175s","start":"2026-08-14T09:20:50.097369Z","end":"2026-08-14T09:20:51.225501Z","steps":["trace[2142054926] 'process raft request' (duration: 1.12541605s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.225907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:50.030674Z","time spent":"1.195230595s","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-08-14T09:20:51.22592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:50.229991Z","time spent":"995.923703ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-08-14T09:20:51.225952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.996149787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:20:51.225876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:49.830111Z","time spent":"1.395758552s","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-08-14T09:20:51.225979Z","caller":"traceutil/trace.go:171","msg":"trace[1942611356] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:660; }","duration":"1.996195268s","start":"2026-08-14T09:20:49.229774Z","end":"2026-08-14T09:20:51.22597Z","steps":["trace[1942611356] 'agreement among raft nodes before linearized reading' (duration: 1.996158427s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.225914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007829921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-08-14T09:20:51.226016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:49.229763Z","time spent":"1.996244649s","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-08-14T09:20:51.226014Z","caller":"traceutil/trace.go:171","msg":"trace[123110657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"2.007933593s","start":"2026-08-14T09:20:49.218074Z","end":"2026-08-14T09:20:51.226007Z","steps":["trace[123110657] 'agreement among raft nodes before linearized reading' (duration: 2.00780792s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.226044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:49.218012Z","time spent":"2.008027055s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:20:51.225996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:50.097341Z","time spent":"1.128582242s","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:20:51.225884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:49.430621Z","time spent":"1.795251712s","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-08-14T09:20:51.226021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.902943863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-08-14T09:20:51.226209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.315551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-08-14T09:20:51.226227Z","caller":"traceutil/trace.go:171","msg":"trace[594295123] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:660; }","duration":"395.392055ms","start":"2026-08-14T09:20:50.830829Z","end":"2026-08-14T09:20:51.226221Z","steps":["trace[594295123] 'agreement among raft nodes before linearized reading' (duration: 395.345242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.226235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.409591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-08-14T09:20:51.226242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:50.830801Z","time spent":"395.436755ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-08-14T09:20:51.226252Z","caller":"traceutil/trace.go:171","msg":"trace[1021930153] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:660; }","duration":"594.453481ms","start":"2026-08-14T09:20:50.631793Z","end":"2026-08-14T09:20:51.226246Z","steps":["trace[1021930153] 'agreement among raft nodes before linearized reading' (duration: 594.410659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.226272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:50.631773Z","time spent":"594.494883ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-08-14T09:20:51.226258Z","caller":"traceutil/trace.go:171","msg":"trace[477750318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"1.903195419s","start":"2026-08-14T09:20:49.32302Z","end":"2026-08-14T09:20:51.226215Z","steps":["trace[477750318] 'agreement among raft nodes before linearized reading' (duration: 1.902854291s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.226286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.483344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4074"} {"level":"info","ts":"2026-08-14T09:20:51.226299Z","caller":"traceutil/trace.go:171","msg":"trace[964159037] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:660; }","duration":"195.495544ms","start":"2026-08-14T09:20:51.030799Z","end":"2026-08-14T09:20:51.226295Z","steps":["trace[964159037] 'agreement among raft nodes before linearized reading' (duration: 195.472264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.226341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.995564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"warn","ts":"2026-08-14T09:20:51.226348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.88126755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:20:51.226312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:49.323011Z","time spent":"1.903287593s","remote":"127.0.0.1:52324","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-14T09:20:51.22636Z","caller":"traceutil/trace.go:171","msg":"trace[1021252273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"1.88129584s","start":"2026-08-14T09:20:49.34506Z","end":"2026-08-14T09:20:51.226356Z","steps":["trace[1021252273] 'agreement among raft nodes before linearized reading' (duration: 1.88127623s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:20:51.22636Z","caller":"traceutil/trace.go:171","msg":"trace[1572634425] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:660; }","duration":"796.043195ms","start":"2026-08-14T09:20:50.430312Z","end":"2026-08-14T09:20:51.226355Z","steps":["trace[1572634425] 'agreement among raft nodes before linearized reading' (duration: 795.993764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.226373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:49.345054Z","time spent":"1.881315051s","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:20:51.226376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:50.430293Z","time spent":"796.079505ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-08-14T09:20:51.226193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.068289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-08-14T09:20:51.226417Z","caller":"traceutil/trace.go:171","msg":"trace[1838642701] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:660; }","duration":"900.306556ms","start":"2026-08-14T09:20:50.326091Z","end":"2026-08-14T09:20:51.226398Z","steps":["trace[1838642701] 'agreement among raft nodes before linearized reading' (duration: 900.041578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.226482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:50.326075Z","time spent":"900.390859ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29593,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-08-14T09:20:51.226604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.75213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-14T09:20:51.22667Z","caller":"traceutil/trace.go:171","msg":"trace[641474840] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:660; }","duration":"987.957346ms","start":"2026-08-14T09:20:50.238696Z","end":"2026-08-14T09:20:51.226653Z","steps":["trace[641474840] 'agreement among raft nodes before linearized reading' (duration: 986.9859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:51.226785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:50.238675Z","time spent":"988.095209ms","remote":"127.0.0.1:52324","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-14T09:20:56.603227Z","caller":"traceutil/trace.go:171","msg":"trace[242299988] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"187.130837ms","start":"2026-08-14T09:20:56.416029Z","end":"2026-08-14T09:20:56.60316Z","steps":["trace[242299988] 'process raft request' (duration: 108.39924ms)","trace[242299988] 'compare' (duration: 78.47056ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:20:58.54839Z","caller":"traceutil/trace.go:171","msg":"trace[580448901] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"100.134553ms","start":"2026-08-14T09:20:58.448229Z","end":"2026-08-14T09:20:58.548364Z","steps":["trace[580448901] 'process raft request' (duration: 99.943008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:20:59.670975Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281591107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:21:00.172079Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281591107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:21:00.673077Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281591107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:21:01.174341Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281591107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:21:01.345682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000395375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-14T09:21:01.3458Z","caller":"traceutil/trace.go:171","msg":"trace[2061740718] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000562009s","start":"2026-08-14T09:20:59.345209Z","end":"2026-08-14T09:21:01.345771Z","steps":["trace[2061740718] 'agreement among raft nodes before linearized reading' (duration: 2.000425175s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:01.345927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:59.345192Z","time spent":"2.000658752s","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/14 09:21:01 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-14T09:21:01.676365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281591107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:21:02.177505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281591107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:21:02.521374Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.390844148s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:21:02.52176Z","caller":"traceutil/trace.go:171","msg":"trace[1961449300] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"3.351229649s","start":"2026-08-14T09:20:59.170504Z","end":"2026-08-14T09:21:02.521734Z","steps":["trace[1961449300] 'read index received' (duration: 3.351004113s)","trace[1961449300] 'applied index is now lower than readState.Index' (duration: 224.486µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:21:02.521839Z","caller":"traceutil/trace.go:171","msg":"trace[1627033783] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"3.391420323s","start":"2026-08-14T09:20:59.1304Z","end":"2026-08-14T09:21:02.52182Z","steps":["trace[1627033783] 'process raft request' (duration: 3.391192327s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:02.521951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.351434534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T09:21:02.521965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:59.130365Z","time spent":"3.391526266s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:21:02.521985Z","caller":"traceutil/trace.go:171","msg":"trace[1171438747] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:729; }","duration":"3.351507706s","start":"2026-08-14T09:20:59.170468Z","end":"2026-08-14T09:21:02.521976Z","steps":["trace[1171438747] 'agreement among raft nodes before linearized reading' (duration: 3.351367413s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:02.52202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:59.170452Z","time spent":"3.351559217s","remote":"127.0.0.1:52256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:21:02.522077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175951514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:21:02.522086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.251059733s","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-14T09:21:02.52213Z","caller":"traceutil/trace.go:171","msg":"trace[18832582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:729; }","duration":"1.176051707s","start":"2026-08-14T09:21:01.346064Z","end":"2026-08-14T09:21:02.522116Z","steps":["trace[18832582] 'agreement among raft nodes before linearized reading' (duration: 1.175965685s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:02.522165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:21:01.346043Z","time spent":"1.176114529s","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:21:02.522159Z","caller":"traceutil/trace.go:171","msg":"trace[1578997576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:729; }","duration":"3.251189026s","start":"2026-08-14T09:20:59.27095Z","end":"2026-08-14T09:21:02.522139Z","steps":["trace[1578997576] 'agreement among raft nodes before linearized reading' (duration: 3.25094492s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:02.522155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.234219845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-14T09:21:02.522195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.237197172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-14T09:21:02.522211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:59.270927Z","time spent":"3.251274018s","remote":"127.0.0.1:52324","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-14T09:21:02.522216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.248337932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-14T09:21:02.522243Z","caller":"traceutil/trace.go:171","msg":"trace[2112225530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:729; }","duration":"3.234338669s","start":"2026-08-14T09:20:59.28788Z","end":"2026-08-14T09:21:02.522218Z","steps":["trace[2112225530] 'agreement among raft nodes before linearized reading' (duration: 3.234083252s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:21:02.522253Z","caller":"traceutil/trace.go:171","msg":"trace[827290421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:729; }","duration":"3.237307956s","start":"2026-08-14T09:20:59.28493Z","end":"2026-08-14T09:21:02.522238Z","steps":["trace[827290421] 'agreement among raft nodes before linearized reading' (duration: 3.237129201s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:21:02.52227Z","caller":"traceutil/trace.go:171","msg":"trace[1526488162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:729; }","duration":"3.248415984s","start":"2026-08-14T09:20:59.273836Z","end":"2026-08-14T09:21:02.522252Z","steps":["trace[1526488162] 'agreement among raft nodes before linearized reading' (duration: 3.24823795s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:02.522275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.23595446s","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-14T09:21:02.522302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:59.273824Z","time spent":"3.248471055s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T09:21:02.522301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:59.284902Z","time spent":"3.237386607s","remote":"127.0.0.1:52324","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-14T09:21:02.522301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:59.287869Z","time spent":"3.23441843s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:21:02.522323Z","caller":"traceutil/trace.go:171","msg":"trace[1539892608] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:729; }","duration":"3.236083333s","start":"2026-08-14T09:20:59.286225Z","end":"2026-08-14T09:21:02.522309Z","steps":["trace[1539892608] 'agreement among raft nodes before linearized reading' (duration: 3.235951669s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:02.522357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:20:59.286205Z","time spent":"3.236143715s","remote":"127.0.0.1:52324","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-14T09:21:02.522361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194552772s","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-14T09:21:02.522395Z","caller":"traceutil/trace.go:171","msg":"trace[1639635731] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:729; }","duration":"1.194633054s","start":"2026-08-14T09:21:01.327753Z","end":"2026-08-14T09:21:02.522386Z","steps":["trace[1639635731] 'agreement among raft nodes before linearized reading' (duration: 1.194566622s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:02.52243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:21:01.32773Z","time spent":"1.194686805s","remote":"127.0.0.1:52328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:21:02.522422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.260142ms","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-14T09:21:02.522472Z","caller":"traceutil/trace.go:171","msg":"trace[675752698] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:729; }","duration":"763.380285ms","start":"2026-08-14T09:21:01.759079Z","end":"2026-08-14T09:21:02.52246Z","steps":["trace[675752698] 'agreement among raft nodes before linearized reading' (duration: 763.250571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:02.522506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:21:01.759039Z","time spent":"763.457427ms","remote":"127.0.0.1:52150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":43,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:21:19.973825Z","caller":"traceutil/trace.go:171","msg":"trace[959298954] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"415.742523ms","start":"2026-08-14T09:21:19.558065Z","end":"2026-08-14T09:21:19.973807Z","steps":["trace[959298954] 'process raft request' (duration: 415.61712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:19.973963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:21:19.558041Z","time spent":"415.852545ms","remote":"127.0.0.1:52296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:21:34.39679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.824787ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-08-14T09:21:34.396889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.213305ms","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-14T09:21:34.39703Z","caller":"traceutil/trace.go:171","msg":"trace[2145159948] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:981; }","duration":"320.376789ms","start":"2026-08-14T09:21:34.076636Z","end":"2026-08-14T09:21:34.397013Z","steps":["trace[2145159948] 'range keys from in-memory index tree' (duration: 320.194224ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:21:34.396967Z","caller":"traceutil/trace.go:171","msg":"trace[643850433] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"408.422552ms","start":"2026-08-14T09:21:33.988522Z","end":"2026-08-14T09:21:34.396945Z","steps":["trace[643850433] 'read index received' (duration: 86.486772ms)","trace[643850433] 'applied index is now lower than readState.Index' (duration: 321.93382ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:21:34.397164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.630497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-96gtf\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-08-14T09:21:34.397238Z","caller":"traceutil/trace.go:171","msg":"trace[2047333764] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-96gtf; range_end:; response_count:1; response_revision:981; }","duration":"408.73876ms","start":"2026-08-14T09:21:33.988472Z","end":"2026-08-14T09:21:34.397211Z","steps":["trace[2047333764] 'agreement among raft nodes before linearized reading' (duration: 408.530514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:34.397314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:21:33.988452Z","time spent":"408.828423ms","remote":"127.0.0.1:52266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5700,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-96gtf\" "} {"level":"info","ts":"2026-08-14T09:21:34.604117Z","caller":"traceutil/trace.go:171","msg":"trace[1213122715] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"146.617855ms","start":"2026-08-14T09:21:34.457478Z","end":"2026-08-14T09:21:34.604095Z","steps":["trace[1213122715] 'read index received' (duration: 146.387259ms)","trace[1213122715] 'applied index is now lower than readState.Index' (duration: 229.816µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:21:34.604149Z","caller":"traceutil/trace.go:171","msg":"trace[1269055616] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"198.952164ms","start":"2026-08-14T09:21:34.405178Z","end":"2026-08-14T09:21:34.60413Z","steps":["trace[1269055616] 'process raft request' (duration: 198.765479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:34.604223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.730767ms","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-14T09:21:34.604257Z","caller":"traceutil/trace.go:171","msg":"trace[1232959735] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:982; }","duration":"146.80845ms","start":"2026-08-14T09:21:34.45744Z","end":"2026-08-14T09:21:34.604248Z","steps":["trace[1232959735] 'agreement among raft nodes before linearized reading' (duration: 146.735978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:35.133729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.690203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:21:35.133814Z","caller":"traceutil/trace.go:171","msg":"trace[1631925961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:987; }","duration":"425.805096ms","start":"2026-08-14T09:21:34.70799Z","end":"2026-08-14T09:21:35.133795Z","steps":["trace[1631925961] 'range keys from in-memory index tree' (duration: 425.485278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:35.133799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.677913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-14T09:21:35.133811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.732455ms","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-14T09:21:35.133862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:21:34.707982Z","time spent":"425.872128ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:21:35.133865Z","caller":"traceutil/trace.go:171","msg":"trace[958902569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"425.762985ms","start":"2026-08-14T09:21:34.708085Z","end":"2026-08-14T09:21:35.133848Z","steps":["trace[958902569] 'range keys from in-memory index tree' (duration: 425.55358ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:21:35.133884Z","caller":"traceutil/trace.go:171","msg":"trace[1975102632] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"425.826337ms","start":"2026-08-14T09:21:34.70804Z","end":"2026-08-14T09:21:35.133867Z","steps":["trace[1975102632] 'range keys from in-memory index tree' (duration: 425.612781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:35.133898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:21:34.708082Z","time spent":"425.808106ms","remote":"127.0.0.1:52324","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-14T09:21:35.133925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:21:34.708035Z","time spent":"425.879968ms","remote":"127.0.0.1:52324","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-14T09:21:35.133715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.100244ms","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-14T09:21:35.134003Z","caller":"traceutil/trace.go:171","msg":"trace[824592296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"426.429642ms","start":"2026-08-14T09:21:34.707562Z","end":"2026-08-14T09:21:35.133992Z","steps":["trace[824592296] 'range keys from in-memory index tree' (duration: 425.938609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:35.134028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:21:34.707555Z","time spent":"426.467484ms","remote":"127.0.0.1:52324","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-14T09:21:35.134031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.794422ms","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-14T09:21:35.134094Z","caller":"traceutil/trace.go:171","msg":"trace[1775061758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"426.872484ms","start":"2026-08-14T09:21:34.7072Z","end":"2026-08-14T09:21:35.134073Z","steps":["trace[1775061758] 'range keys from in-memory index tree' (duration: 426.685609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:35.134142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:21:34.707185Z","time spent":"426.946816ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:22:06.529568Z","caller":"traceutil/trace.go:171","msg":"trace[183292010] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1149; }","duration":"184.419659ms","start":"2026-08-14T09:22:06.345128Z","end":"2026-08-14T09:22:06.529547Z","steps":["trace[183292010] 'read index received' (duration: 184.248784ms)","trace[183292010] 'applied index is now lower than readState.Index' (duration: 169.865µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:22:06.529671Z","caller":"traceutil/trace.go:171","msg":"trace[1468853786] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"248.273622ms","start":"2026-08-14T09:22:06.28137Z","end":"2026-08-14T09:22:06.529644Z","steps":["trace[1468853786] 'process raft request' (duration: 248.045346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:06.529712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.553472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:22:06.529735Z","caller":"traceutil/trace.go:171","msg":"trace[1154633264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1102; }","duration":"184.644754ms","start":"2026-08-14T09:22:06.345082Z","end":"2026-08-14T09:22:06.529727Z","steps":["trace[1154633264] 'agreement among raft nodes before linearized reading' (duration: 184.539672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:07.832266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281591967,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:22:08.22239Z","caller":"traceutil/trace.go:171","msg":"trace[1719135291] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"890.39896ms","start":"2026-08-14T09:22:07.331973Z","end":"2026-08-14T09:22:08.222372Z","steps":["trace[1719135291] 'read index received' (duration: 890.281787ms)","trace[1719135291] 'applied index is now lower than readState.Index' (duration: 116.503µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:22:08.222454Z","caller":"traceutil/trace.go:171","msg":"trace[1336276116] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"907.442757ms","start":"2026-08-14T09:22:07.314981Z","end":"2026-08-14T09:22:08.222423Z","steps":["trace[1336276116] 'process raft request' (duration: 907.280143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:08.222596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.254656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-14T09:22:08.222596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.607065ms","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-14T09:22:08.222633Z","caller":"traceutil/trace.go:171","msg":"trace[439641521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1104; }","duration":"890.321438ms","start":"2026-08-14T09:22:07.332301Z","end":"2026-08-14T09:22:08.222622Z","steps":["trace[439641521] 'agreement among raft nodes before linearized reading' (duration: 890.199595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:08.222632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:07.314968Z","time spent":"907.5761ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:22:08.22264Z","caller":"traceutil/trace.go:171","msg":"trace[54850571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1104; }","duration":"890.692127ms","start":"2026-08-14T09:22:07.331937Z","end":"2026-08-14T09:22:08.222629Z","steps":["trace[54850571] 'agreement among raft nodes before linearized reading' (duration: 890.543183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:08.222649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.022565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-14T09:22:08.222666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:07.332294Z","time spent":"890.363539ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:22:08.222675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:07.331917Z","time spent":"890.751178ms","remote":"127.0.0.1:52324","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-14T09:22:08.222696Z","caller":"traceutil/trace.go:171","msg":"trace[754381967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1104; }","duration":"886.087227ms","start":"2026-08-14T09:22:07.336596Z","end":"2026-08-14T09:22:08.222683Z","steps":["trace[754381967] 'agreement among raft nodes before linearized reading' (duration: 885.940513ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:08.22273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:07.336588Z","time spent":"886.133029ms","remote":"127.0.0.1:52324","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-14T09:22:08.222749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.247153ms","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-14T09:22:08.222828Z","caller":"traceutil/trace.go:171","msg":"trace[1100280970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1104; }","duration":"888.372986ms","start":"2026-08-14T09:22:07.334432Z","end":"2026-08-14T09:22:08.222805Z","steps":["trace[1100280970] 'agreement among raft nodes before linearized reading' (duration: 888.180511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:08.222932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:07.334408Z","time spent":"888.50925ms","remote":"127.0.0.1:52324","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-14T09:22:08.846182Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281591970,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:22:08.931146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.911301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:22:08.93139Z","caller":"traceutil/trace.go:171","msg":"trace[1427296059] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"704.188226ms","start":"2026-08-14T09:22:08.227166Z","end":"2026-08-14T09:22:08.931354Z","steps":["trace[1427296059] 'process raft request' (duration: 704.093723ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:08.931446Z","caller":"traceutil/trace.go:171","msg":"trace[2117472792] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"704.255937ms","start":"2026-08-14T09:22:08.227166Z","end":"2026-08-14T09:22:08.931422Z","steps":["trace[2117472792] 'process raft request' (duration: 58.996547ms)","trace[2117472792] 'compare' (duration: 644.51122ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:22:08.931476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:08.227147Z","time spent":"704.283729ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:22:08.93157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:08.227147Z","time spent":"704.360331ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:22:08.967417Z","caller":"traceutil/trace.go:171","msg":"trace[1957610491] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"738.607718ms","start":"2026-08-14T09:22:08.228784Z","end":"2026-08-14T09:22:08.967391Z","steps":["trace[1957610491] 'process raft request' (duration: 738.436043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:08.967563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:08.228774Z","time spent":"738.714352ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:22:08.967685Z","caller":"traceutil/trace.go:171","msg":"trace[64434579] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"738.723752ms","start":"2026-08-14T09:22:08.228891Z","end":"2026-08-14T09:22:08.967615Z","steps":["trace[64434579] 'process raft request' (duration: 738.462374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:08.967826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:08.22886Z","time spent":"738.889696ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:22:08.982416Z","caller":"traceutil/trace.go:171","msg":"trace[487470368] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1155; }","duration":"636.823417ms","start":"2026-08-14T09:22:08.345574Z","end":"2026-08-14T09:22:08.982398Z","steps":["trace[487470368] 'read index received' (duration: 621.771912ms)","trace[487470368] 'applied index is now lower than readState.Index' (duration: 15.050775ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:22:08.982467Z","caller":"traceutil/trace.go:171","msg":"trace[1764182889] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"147.035967ms","start":"2026-08-14T09:22:08.835403Z","end":"2026-08-14T09:22:08.982439Z","steps":["trace[1764182889] 'process raft request' (duration: 146.891964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:08.982568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.967381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:22:08.98261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.29785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-14T09:22:08.982623Z","caller":"traceutil/trace.go:171","msg":"trace[1659613016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1109; }","duration":"637.083774ms","start":"2026-08-14T09:22:08.345526Z","end":"2026-08-14T09:22:08.98261Z","steps":["trace[1659613016] 'agreement among raft nodes before linearized reading' (duration: 636.958711ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:08.982649Z","caller":"traceutil/trace.go:171","msg":"trace[1114157470] range","detail":"{range_begin:/registry/masterleases/199.19.213.195; range_end:; response_count:1; response_revision:1109; }","duration":"475.390293ms","start":"2026-08-14T09:22:08.507251Z","end":"2026-08-14T09:22:08.982641Z","steps":["trace[1114157470] 'agreement among raft nodes before linearized reading' (duration: 475.261129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:08.982659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:08.345507Z","time spent":"637.142936ms","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:22:08.982682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:08.507229Z","time spent":"475.445944ms","remote":"127.0.0.1:52120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.195\" "} {"level":"info","ts":"2026-08-14T09:22:10.351668Z","caller":"traceutil/trace.go:171","msg":"trace[54143547] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"119.057264ms","start":"2026-08-14T09:22:10.232574Z","end":"2026-08-14T09:22:10.351631Z","steps":["trace[54143547] 'process raft request' (duration: 118.826468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:11.225024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.171816ms","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-14T09:22:11.225088Z","caller":"traceutil/trace.go:171","msg":"trace[2058340493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1111; }","duration":"252.275188ms","start":"2026-08-14T09:22:10.9728Z","end":"2026-08-14T09:22:11.225075Z","steps":["trace[2058340493] 'range keys from in-memory index tree' (duration: 252.059003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:11.225118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.291702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:22:11.225173Z","caller":"traceutil/trace.go:171","msg":"trace[620260095] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1111; }","duration":"761.386064ms","start":"2026-08-14T09:22:10.463771Z","end":"2026-08-14T09:22:11.225158Z","steps":["trace[620260095] 'count revisions from in-memory index tree' (duration: 761.20664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:11.2252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:10.463754Z","time spent":"761.438216ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:22:11.225427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.733452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-14T09:22:11.225506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.533993ms","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-14T09:22:11.225532Z","caller":"traceutil/trace.go:171","msg":"trace[367512666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1111; }","duration":"290.605015ms","start":"2026-08-14T09:22:10.93492Z","end":"2026-08-14T09:22:11.225525Z","steps":["trace[367512666] 'range keys from in-memory index tree' (duration: 290.42755ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:11.225518Z","caller":"traceutil/trace.go:171","msg":"trace[1602575050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1111; }","duration":"289.844095ms","start":"2026-08-14T09:22:10.935656Z","end":"2026-08-14T09:22:11.2255Z","steps":["trace[1602575050] 'range keys from in-memory index tree' (duration: 289.623249ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:11.225653Z","caller":"traceutil/trace.go:171","msg":"trace[257129910] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"237.482541ms","start":"2026-08-14T09:22:10.98816Z","end":"2026-08-14T09:22:11.225643Z","steps":["trace[257129910] 'process raft request' (duration: 230.303222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:11.22505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.10278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:22:11.226494Z","caller":"traceutil/trace.go:171","msg":"trace[1343582639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1111; }","duration":"254.560159ms","start":"2026-08-14T09:22:10.9719Z","end":"2026-08-14T09:22:11.22646Z","steps":["trace[1343582639] 'range keys from in-memory index tree' (duration: 252.978977ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:12.759754Z","caller":"traceutil/trace.go:171","msg":"trace[1107674617] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"400.228661ms","start":"2026-08-14T09:22:12.359498Z","end":"2026-08-14T09:22:12.759727Z","steps":["trace[1107674617] 'process raft request' (duration: 399.970894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:12.759978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:12.359471Z","time spent":"400.394024ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:22:13.510244Z","caller":"traceutil/trace.go:171","msg":"trace[344104306] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"268.203277ms","start":"2026-08-14T09:22:13.242015Z","end":"2026-08-14T09:22:13.510219Z","steps":["trace[344104306] 'read index received' (duration: 268.027103ms)","trace[344104306] 'applied index is now lower than readState.Index' (duration: 174.764µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:22:13.510288Z","caller":"traceutil/trace.go:171","msg":"trace[1976108089] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"278.505267ms","start":"2026-08-14T09:22:13.231752Z","end":"2026-08-14T09:22:13.510257Z","steps":["trace[1976108089] 'process raft request' (duration: 278.283361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:13.5105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.475665ms","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-14T09:22:13.510568Z","caller":"traceutil/trace.go:171","msg":"trace[198459603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1118; }","duration":"268.595498ms","start":"2026-08-14T09:22:13.241955Z","end":"2026-08-14T09:22:13.510551Z","steps":["trace[198459603] 'agreement among raft nodes before linearized reading' (duration: 268.390652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:13.510574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.436551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:22:13.510623Z","caller":"traceutil/trace.go:171","msg":"trace[984663619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1118; }","duration":"165.533033ms","start":"2026-08-14T09:22:13.345079Z","end":"2026-08-14T09:22:13.510612Z","steps":["trace[984663619] 'agreement among raft nodes before linearized reading' (duration: 165.41825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:13.510657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.076947ms","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-14T09:22:13.510694Z","caller":"traceutil/trace.go:171","msg":"trace[616028038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1118; }","duration":"238.134978ms","start":"2026-08-14T09:22:13.272551Z","end":"2026-08-14T09:22:13.510686Z","steps":["trace[616028038] 'agreement among raft nodes before linearized reading' (duration: 238.010775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:13.51078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.719813ms","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-14T09:22:13.510869Z","caller":"traceutil/trace.go:171","msg":"trace[1311769196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1118; }","duration":"238.811726ms","start":"2026-08-14T09:22:13.272021Z","end":"2026-08-14T09:22:13.510833Z","steps":["trace[1311769196] 'agreement among raft nodes before linearized reading' (duration: 238.601671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:13.511099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.625267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:22:13.511147Z","caller":"traceutil/trace.go:171","msg":"trace[1233480205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1118; }","duration":"239.706399ms","start":"2026-08-14T09:22:13.271428Z","end":"2026-08-14T09:22:13.511134Z","steps":["trace[1233480205] 'agreement among raft nodes before linearized reading' (duration: 239.031411ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:13.652109Z","caller":"traceutil/trace.go:171","msg":"trace[1508497090] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"137.272081ms","start":"2026-08-14T09:22:13.514815Z","end":"2026-08-14T09:22:13.652087Z","steps":["trace[1508497090] 'process raft request' (duration: 137.139778ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:13.652386Z","caller":"traceutil/trace.go:171","msg":"trace[707356567] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"137.518877ms","start":"2026-08-14T09:22:13.514838Z","end":"2026-08-14T09:22:13.652357Z","steps":["trace[707356567] 'process raft request' (duration: 137.21825ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:13.652397Z","caller":"traceutil/trace.go:171","msg":"trace[756111852] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"136.81563ms","start":"2026-08-14T09:22:13.515556Z","end":"2026-08-14T09:22:13.652371Z","steps":["trace[756111852] 'process raft request' (duration: 136.703997ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:14.478834Z","caller":"traceutil/trace.go:171","msg":"trace[258259884] linearizableReadLoop","detail":"{readStateIndex:1172; appliedIndex:1171; }","duration":"133.270067ms","start":"2026-08-14T09:22:14.345547Z","end":"2026-08-14T09:22:14.478817Z","steps":["trace[258259884] 'read index received' (duration: 115.85214ms)","trace[258259884] 'applied index is now lower than readState.Index' (duration: 17.416747ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:22:14.478948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.39503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:22:14.478969Z","caller":"traceutil/trace.go:171","msg":"trace[1885273110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1122; }","duration":"133.468252ms","start":"2026-08-14T09:22:14.345494Z","end":"2026-08-14T09:22:14.478963Z","steps":["trace[1885273110] 'agreement among raft nodes before linearized reading' (duration: 133.41027ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:14.813014Z","caller":"traceutil/trace.go:171","msg":"trace[1935159841] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"236.33507ms","start":"2026-08-14T09:22:14.576647Z","end":"2026-08-14T09:22:14.812982Z","steps":["trace[1935159841] 'process raft request' (duration: 236.064163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:32.270368Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281592262,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:22:32.293704Z","caller":"traceutil/trace.go:171","msg":"trace[1401381187] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"524.428698ms","start":"2026-08-14T09:22:31.769253Z","end":"2026-08-14T09:22:32.293681Z","steps":["trace[1401381187] 'read index received' (duration: 524.258284ms)","trace[1401381187] 'applied index is now lower than readState.Index' (duration: 169.434µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:22:32.293811Z","caller":"traceutil/trace.go:171","msg":"trace[1136788133] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"526.145123ms","start":"2026-08-14T09:22:31.767656Z","end":"2026-08-14T09:22:32.293802Z","steps":["trace[1136788133] 'process raft request' (duration: 525.900367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:32.29391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:31.767638Z","time spent":"526.210816ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:22:32.294121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.749085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:22:32.294188Z","caller":"traceutil/trace.go:171","msg":"trace[1754203907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1230; }","duration":"490.854468ms","start":"2026-08-14T09:22:31.803317Z","end":"2026-08-14T09:22:32.294172Z","steps":["trace[1754203907] 'agreement among raft nodes before linearized reading' (duration: 490.670573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:32.294227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:31.8033Z","time spent":"490.917319ms","remote":"127.0.0.1:52324","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-14T09:22:32.29423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.906826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:22:32.294264Z","caller":"traceutil/trace.go:171","msg":"trace[1422363077] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1230; }","duration":"124.970528ms","start":"2026-08-14T09:22:32.169284Z","end":"2026-08-14T09:22:32.294255Z","steps":["trace[1422363077] 'agreement among raft nodes before linearized reading' (duration: 124.915797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:32.294436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.070994ms","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-14T09:22:32.294458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.202608ms","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-14T09:22:32.294465Z","caller":"traceutil/trace.go:171","msg":"trace[1821554295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1230; }","duration":"491.131045ms","start":"2026-08-14T09:22:31.803325Z","end":"2026-08-14T09:22:32.294456Z","steps":["trace[1821554295] 'agreement among raft nodes before linearized reading' (duration: 491.049843ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:32.294484Z","caller":"traceutil/trace.go:171","msg":"trace[1382751010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1230; }","duration":"525.25965ms","start":"2026-08-14T09:22:31.769217Z","end":"2026-08-14T09:22:32.294477Z","steps":["trace[1382751010] 'agreement among raft nodes before linearized reading' (duration: 525.162787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:32.294492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:31.803306Z","time spent":"491.179036ms","remote":"127.0.0.1:52324","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-14T09:22:32.294504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:31.769205Z","time spent":"525.294261ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:22:33.843827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281592269,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:22:33.909002Z","caller":"traceutil/trace.go:171","msg":"trace[1817374676] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"922.754639ms","start":"2026-08-14T09:22:32.986225Z","end":"2026-08-14T09:22:33.90898Z","steps":["trace[1817374676] 'process raft request' (duration: 922.626296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:33.909131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:32.986184Z","time spent":"922.879733ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:22:33.945838Z","caller":"traceutil/trace.go:171","msg":"trace[463880863] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1288; }","duration":"602.558358ms","start":"2026-08-14T09:22:33.343259Z","end":"2026-08-14T09:22:33.945818Z","steps":["trace[463880863] 'read index received' (duration: 565.593449ms)","trace[463880863] 'applied index is now lower than readState.Index' (duration: 36.964119ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:22:33.946077Z","caller":"traceutil/trace.go:171","msg":"trace[418066710] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"289.514806ms","start":"2026-08-14T09:22:33.656545Z","end":"2026-08-14T09:22:33.94606Z","steps":["trace[418066710] 'process raft request' (duration: 287.940174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:33.946113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.838326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:22:33.94614Z","caller":"traceutil/trace.go:171","msg":"trace[1134353922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1235; }","duration":"602.906578ms","start":"2026-08-14T09:22:33.343225Z","end":"2026-08-14T09:22:33.946131Z","steps":["trace[1134353922] 'agreement among raft nodes before linearized reading' (duration: 602.845786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:33.946119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.35708ms","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":"warn","ts":"2026-08-14T09:22:33.946164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:33.343209Z","time spent":"602.947128ms","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:22:33.94618Z","caller":"traceutil/trace.go:171","msg":"trace[1847579098] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1235; }","duration":"244.469384ms","start":"2026-08-14T09:22:33.701698Z","end":"2026-08-14T09:22:33.946167Z","steps":["trace[1847579098] 'agreement among raft nodes before linearized reading' (duration: 244.33812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:34.799913Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281592274,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:22:35.300579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281592274,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:22:35.586641Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.606517679s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-14T09:22:35.597175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.520032779s","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-14T09:22:35.59727Z","caller":"traceutil/trace.go:171","msg":"trace[1123310594] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1235; }","duration":"1.520145992s","start":"2026-08-14T09:22:34.077112Z","end":"2026-08-14T09:22:35.597258Z","steps":["trace[1123310594] 'range keys from in-memory index tree' (duration: 1.520019089s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:35.603154Z","caller":"traceutil/trace.go:171","msg":"trace[1901693317] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1290; }","duration":"1.303759406s","start":"2026-08-14T09:22:34.299373Z","end":"2026-08-14T09:22:35.603132Z","steps":["trace[1901693317] 'read index received' (duration: 1.287490929s)","trace[1901693317] 'applied index is now lower than readState.Index' (duration: 16.267607ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:22:35.603271Z","caller":"traceutil/trace.go:171","msg":"trace[104945225] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"428.856151ms","start":"2026-08-14T09:22:35.174401Z","end":"2026-08-14T09:22:35.603257Z","steps":["trace[104945225] 'process raft request' (duration: 428.564694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:35.603406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:35.174368Z","time spent":"428.951154ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:22:35.603504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298129088s","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-14T09:22:35.603559Z","caller":"traceutil/trace.go:171","msg":"trace[1077787342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1236; }","duration":"1.298218811s","start":"2026-08-14T09:22:34.305327Z","end":"2026-08-14T09:22:35.603545Z","steps":["trace[1077787342] 'agreement among raft nodes before linearized reading' (duration: 1.298068657s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:35.603586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:34.30531Z","time spent":"1.298268992s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:22:35.603687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30432209s","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-14T09:22:35.603702Z","caller":"traceutil/trace.go:171","msg":"trace[1799820491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1236; }","duration":"1.304370442s","start":"2026-08-14T09:22:34.299327Z","end":"2026-08-14T09:22:35.603697Z","steps":["trace[1799820491] 'agreement among raft nodes before linearized reading' (duration: 1.304323361s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:35.603717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:34.299276Z","time spent":"1.304436844s","remote":"127.0.0.1:52324","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-14T09:22:35.603787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.291424ms","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-14T09:22:35.603806Z","caller":"traceutil/trace.go:171","msg":"trace[350246720] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1236; }","duration":"854.324234ms","start":"2026-08-14T09:22:34.749476Z","end":"2026-08-14T09:22:35.6038Z","steps":["trace[350246720] 'agreement among raft nodes before linearized reading' (duration: 854.281333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:35.603821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:34.749412Z","time spent":"854.405027ms","remote":"127.0.0.1:34544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"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-14T09:22:35.603923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260216044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:22:35.603939Z","caller":"traceutil/trace.go:171","msg":"trace[1540529488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"1.260253994s","start":"2026-08-14T09:22:34.343679Z","end":"2026-08-14T09:22:35.603933Z","steps":["trace[1540529488] 'agreement among raft nodes before linearized reading' (duration: 1.260228443s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:35.603952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:34.343666Z","time spent":"1.260282955s","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:22:35.604069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29288052s","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-14T09:22:35.604084Z","caller":"traceutil/trace.go:171","msg":"trace[937752963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1236; }","duration":"1.292933832s","start":"2026-08-14T09:22:34.311145Z","end":"2026-08-14T09:22:35.604079Z","steps":["trace[937752963] 'agreement among raft nodes before linearized reading' (duration: 1.29288316s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:35.604098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:34.311132Z","time spent":"1.292962133s","remote":"127.0.0.1:52324","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-14T09:22:35.604174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294167044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:22:35.604187Z","caller":"traceutil/trace.go:171","msg":"trace[2003905605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1236; }","duration":"1.294191654s","start":"2026-08-14T09:22:34.309991Z","end":"2026-08-14T09:22:35.604182Z","steps":["trace[2003905605] 'agreement among raft nodes before linearized reading' (duration: 1.294149413s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:35.604203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:34.309984Z","time spent":"1.294215136s","remote":"127.0.0.1:52324","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-14T09:22:56.200775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281592507,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:22:56.474732Z","caller":"traceutil/trace.go:171","msg":"trace[1841159631] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"776.011579ms","start":"2026-08-14T09:22:55.698694Z","end":"2026-08-14T09:22:56.474706Z","steps":["trace[1841159631] 'process raft request' (duration: 775.854505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:56.474901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:55.698684Z","time spent":"776.123722ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:22:57.023179Z","caller":"traceutil/trace.go:171","msg":"trace[1243169037] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1395; }","duration":"1.323233707s","start":"2026-08-14T09:22:55.699928Z","end":"2026-08-14T09:22:57.023161Z","steps":["trace[1243169037] 'read index received' (duration: 774.636424ms)","trace[1243169037] 'applied index is now lower than readState.Index' (duration: 548.596683ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:22:57.023414Z","caller":"traceutil/trace.go:171","msg":"trace[1775287980] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"976.532251ms","start":"2026-08-14T09:22:56.046873Z","end":"2026-08-14T09:22:57.023405Z","steps":["trace[1775287980] 'process raft request' (duration: 976.149761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:57.023502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:56.046862Z","time spent":"976.578352ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:22:57.023643Z","caller":"traceutil/trace.go:171","msg":"trace[1751649615] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"919.480543ms","start":"2026-08-14T09:22:56.104155Z","end":"2026-08-14T09:22:57.023635Z","steps":["trace[1751649615] 'process raft request' (duration: 918.9759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:57.023687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:56.104138Z","time spent":"919.528674ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:22:57.023829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323913915s","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-14T09:22:57.023857Z","caller":"traceutil/trace.go:171","msg":"trace[1634049387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1338; }","duration":"1.323983947s","start":"2026-08-14T09:22:55.699866Z","end":"2026-08-14T09:22:57.02385Z","steps":["trace[1634049387] 'agreement among raft nodes before linearized reading' (duration: 1.323894054s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:57.023877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:55.699844Z","time spent":"1.324028047s","remote":"127.0.0.1:52324","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-14T09:22:57.024003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310249995s","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-14T09:22:57.024029Z","caller":"traceutil/trace.go:171","msg":"trace[1906106645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1338; }","duration":"1.310305727s","start":"2026-08-14T09:22:55.713717Z","end":"2026-08-14T09:22:57.024023Z","steps":["trace[1906106645] 'agreement among raft nodes before linearized reading' (duration: 1.310239895s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:57.024051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:55.713698Z","time spent":"1.310348038s","remote":"127.0.0.1:52324","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-14T09:22:57.024133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.4653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:22:57.024156Z","caller":"traceutil/trace.go:171","msg":"trace[2025090743] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1338; }","duration":"115.530331ms","start":"2026-08-14T09:22:56.90862Z","end":"2026-08-14T09:22:57.024151Z","steps":["trace[2025090743] 'agreement among raft nodes before linearized reading' (duration: 115.49653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:57.024274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.682384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:22:57.024298Z","caller":"traceutil/trace.go:171","msg":"trace[1626383209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1338; }","duration":"657.736786ms","start":"2026-08-14T09:22:56.366555Z","end":"2026-08-14T09:22:57.024292Z","steps":["trace[1626383209] 'agreement among raft nodes before linearized reading' (duration: 657.700515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:57.024315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:56.366537Z","time spent":"657.773957ms","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:22:57.024447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.631154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T09:22:57.024473Z","caller":"traceutil/trace.go:171","msg":"trace[1111281496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1338; }","duration":"981.681155ms","start":"2026-08-14T09:22:56.042786Z","end":"2026-08-14T09:22:57.024468Z","steps":["trace[1111281496] 'agreement among raft nodes before linearized reading' (duration: 981.615103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:57.024491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:56.042771Z","time spent":"981.715166ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:22:57.024601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30510808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:22:57.024688Z","caller":"traceutil/trace.go:171","msg":"trace[2059066861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1338; }","duration":"1.305234834s","start":"2026-08-14T09:22:55.719436Z","end":"2026-08-14T09:22:57.024671Z","steps":["trace[2059066861] 'agreement among raft nodes before linearized reading' (duration: 1.305034079s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:57.024727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:55.719416Z","time spent":"1.305304096s","remote":"127.0.0.1:52324","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-14T09:22:57.373982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.16836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:22:57.374072Z","caller":"traceutil/trace.go:171","msg":"trace[480737768] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"344.873257ms","start":"2026-08-14T09:22:57.029182Z","end":"2026-08-14T09:22:57.374056Z","steps":["trace[480737768] 'process raft request' (duration: 173.556943ms)","trace[480737768] 'compare' (duration: 170.911154ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:22:57.374127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:57.029167Z","time spent":"344.93083ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:22:57.417657Z","caller":"traceutil/trace.go:171","msg":"trace[1343171014] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"388.40301ms","start":"2026-08-14T09:22:57.02923Z","end":"2026-08-14T09:22:57.417633Z","steps":["trace[1343171014] 'process raft request' (duration: 387.821104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:57.417786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:57.029209Z","time spent":"388.510953ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:22:57.41793Z","caller":"traceutil/trace.go:171","msg":"trace[1434258725] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"387.606ms","start":"2026-08-14T09:22:57.030313Z","end":"2026-08-14T09:22:57.417919Z","steps":["trace[1434258725] 'process raft request' (duration: 386.857069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:57.417997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:57.030305Z","time spent":"387.656511ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:22:57.418169Z","caller":"traceutil/trace.go:171","msg":"trace[2074631449] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"383.496032ms","start":"2026-08-14T09:22:57.034656Z","end":"2026-08-14T09:22:57.418152Z","steps":["trace[2074631449] 'process raft request' (duration: 382.554306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:57.418255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:57.034639Z","time spent":"383.575323ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:22:58.844951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281592519,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:22:58.911005Z","caller":"traceutil/trace.go:171","msg":"trace[755410428] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"566.660318ms","start":"2026-08-14T09:22:58.344313Z","end":"2026-08-14T09:22:58.910973Z","steps":["trace[755410428] 'read index received' (duration: 566.318429ms)","trace[755410428] 'applied index is now lower than readState.Index' (duration: 340.549µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:22:58.911045Z","caller":"traceutil/trace.go:171","msg":"trace[165358364] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"595.532274ms","start":"2026-08-14T09:22:58.315485Z","end":"2026-08-14T09:22:58.911017Z","steps":["trace[165358364] 'process raft request' (duration: 595.249187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:58.911118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.788881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:22:58.911173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:58.315462Z","time spent":"595.655017ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:22:58.911195Z","caller":"traceutil/trace.go:171","msg":"trace[243808930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"566.922324ms","start":"2026-08-14T09:22:58.344258Z","end":"2026-08-14T09:22:58.911181Z","steps":["trace[243808930] 'agreement among raft nodes before linearized reading' (duration: 566.77945ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:58.911233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:58.344216Z","time spent":"567.008896ms","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:22:58.911294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.571499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-14T09:22:58.911346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.826663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-14T09:22:58.911367Z","caller":"traceutil/trace.go:171","msg":"trace[584188403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1344; }","duration":"432.688362ms","start":"2026-08-14T09:22:58.478657Z","end":"2026-08-14T09:22:58.911346Z","steps":["trace[584188403] 'agreement among raft nodes before linearized reading' (duration: 432.484497ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:58.91141Z","caller":"traceutil/trace.go:171","msg":"trace[1207541823] range","detail":"{range_begin:/registry/masterleases/199.19.213.195; range_end:; response_count:1; response_revision:1344; }","duration":"401.925476ms","start":"2026-08-14T09:22:58.50947Z","end":"2026-08-14T09:22:58.911395Z","steps":["trace[1207541823] 'agreement among raft nodes before linearized reading' (duration: 401.757671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:22:58.911426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:58.478634Z","time spent":"432.776765ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:22:58.911459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:58.509451Z","time spent":"401.996237ms","remote":"127.0.0.1:52120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.195\" "} {"level":"warn","ts":"2026-08-14T09:23:00.270766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.236272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:23:00.270953Z","caller":"traceutil/trace.go:171","msg":"trace[1243878725] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"1.355835502s","start":"2026-08-14T09:22:58.91509Z","end":"2026-08-14T09:23:00.270925Z","steps":["trace[1243878725] 'process raft request' (duration: 493.343254ms)","trace[1243878725] 'compare' (duration: 862.045586ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:23:00.271082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:58.915067Z","time spent":"1.355945974s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:23:00.434119Z","caller":"traceutil/trace.go:171","msg":"trace[115279226] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1405; }","duration":"1.086980658s","start":"2026-08-14T09:22:59.347114Z","end":"2026-08-14T09:23:00.434094Z","steps":["trace[115279226] 'read index received' (duration: 61.33258ms)","trace[115279226] 'applied index is now lower than readState.Index' (duration: 1.025646978s)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:23:00.434278Z","caller":"traceutil/trace.go:171","msg":"trace[773959131] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"1.404075627s","start":"2026-08-14T09:22:59.030169Z","end":"2026-08-14T09:23:00.434245Z","steps":["trace[773959131] 'process raft request' (duration: 1.403855821s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:00.434336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087209604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:23:00.434402Z","caller":"traceutil/trace.go:171","msg":"trace[216392355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"1.087318607s","start":"2026-08-14T09:22:59.347065Z","end":"2026-08-14T09:23:00.434383Z","steps":["trace[216392355] 'agreement among raft nodes before linearized reading' (duration: 1.087215464s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:00.434426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:59.030081Z","time spent":"1.404273132s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:23:00.434455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:59.347045Z","time spent":"1.087397549s","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:23:00.434453Z","caller":"traceutil/trace.go:171","msg":"trace[679933007] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"1.447174028s","start":"2026-08-14T09:22:58.987249Z","end":"2026-08-14T09:23:00.434423Z","steps":["trace[679933007] 'process raft request' (duration: 1.383465366s)","trace[679933007] 'compare' (duration: 63.039353ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:23:00.434604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:58.987223Z","time spent":"1.447310262s","remote":"127.0.0.1:52120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:23:00.434625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.20783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:23:00.434687Z","caller":"traceutil/trace.go:171","msg":"trace[348118434] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1347; }","duration":"971.314633ms","start":"2026-08-14T09:22:59.463356Z","end":"2026-08-14T09:23:00.434671Z","steps":["trace[348118434] 'agreement among raft nodes before linearized reading' (duration: 971.20077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:00.434721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:59.463334Z","time spent":"971.378385ms","remote":"127.0.0.1:52178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:23:00.434957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.303323ms","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":"warn","ts":"2026-08-14T09:23:00.434967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01161466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:23:00.43499Z","caller":"traceutil/trace.go:171","msg":"trace[152755066] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1347; }","duration":"623.379925ms","start":"2026-08-14T09:22:59.811602Z","end":"2026-08-14T09:23:00.434982Z","steps":["trace[152755066] 'agreement among raft nodes before linearized reading' (duration: 623.259832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:00.435014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:59.811578Z","time spent":"623.431037ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-14T09:23:00.43501Z","caller":"traceutil/trace.go:171","msg":"trace[1524723801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"1.011706823s","start":"2026-08-14T09:22:59.423291Z","end":"2026-08-14T09:23:00.434998Z","steps":["trace[1524723801] 'agreement among raft nodes before linearized reading' (duration: 1.011489137s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:00.435009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011663732s","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-14T09:23:00.435047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:59.423263Z","time spent":"1.011770785s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:23:00.435046Z","caller":"traceutil/trace.go:171","msg":"trace[1606667431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1347; }","duration":"1.011829856s","start":"2026-08-14T09:22:59.423206Z","end":"2026-08-14T09:23:00.435035Z","steps":["trace[1606667431] 'agreement among raft nodes before linearized reading' (duration: 1.011716603s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:00.435076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:59.423185Z","time spent":"1.011884147s","remote":"127.0.0.1:52324","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-14T09:23:00.435292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011845177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T09:23:00.435406Z","caller":"traceutil/trace.go:171","msg":"trace[1069502789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1347; }","duration":"1.012030141s","start":"2026-08-14T09:22:59.423356Z","end":"2026-08-14T09:23:00.435386Z","steps":["trace[1069502789] 'agreement among raft nodes before linearized reading' (duration: 1.011656031s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:00.435518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:59.423338Z","time spent":"1.012132624s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:23:00.434719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056813087s","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-14T09:23:00.436582Z","caller":"traceutil/trace.go:171","msg":"trace[1564327855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1347; }","duration":"1.058724426s","start":"2026-08-14T09:22:59.377801Z","end":"2026-08-14T09:23:00.436525Z","steps":["trace[1564327855] 'agreement among raft nodes before linearized reading' (duration: 1.056651572s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:00.436673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:22:59.377757Z","time spent":"1.058897851s","remote":"127.0.0.1:52324","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-14T09:23:01.32617Z","caller":"traceutil/trace.go:171","msg":"trace[1622027177] linearizableReadLoop","detail":"{readStateIndex:1416; appliedIndex:1415; }","duration":"262.886848ms","start":"2026-08-14T09:23:01.063259Z","end":"2026-08-14T09:23:01.326146Z","steps":["trace[1622027177] 'read index received' (duration: 262.715493ms)","trace[1622027177] 'applied index is now lower than readState.Index' (duration: 170.285µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:23:01.3262Z","caller":"traceutil/trace.go:171","msg":"trace[717308345] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"284.375711ms","start":"2026-08-14T09:23:01.041796Z","end":"2026-08-14T09:23:01.326171Z","steps":["trace[717308345] 'process raft request' (duration: 284.223097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:01.326344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.065172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:23:01.326389Z","caller":"traceutil/trace.go:171","msg":"trace[638468218] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1355; }","duration":"263.152344ms","start":"2026-08-14T09:23:01.063223Z","end":"2026-08-14T09:23:01.326376Z","steps":["trace[638468218] 'agreement among raft nodes before linearized reading' (duration: 263.049832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:01.833346Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281592545,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:23:01.847115Z","caller":"traceutil/trace.go:171","msg":"trace[861700023] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"514.463888ms","start":"2026-08-14T09:23:01.332634Z","end":"2026-08-14T09:23:01.847098Z","steps":["trace[861700023] 'read index received' (duration: 514.405467ms)","trace[861700023] 'applied index is now lower than readState.Index' (duration: 57.731µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:23:01.847196Z","caller":"traceutil/trace.go:171","msg":"trace[42670977] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"516.284885ms","start":"2026-08-14T09:23:01.330881Z","end":"2026-08-14T09:23:01.847166Z","steps":["trace[42670977] 'process raft request' (duration: 516.074089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:01.847258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.596851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:23:01.847294Z","caller":"traceutil/trace.go:171","msg":"trace[790082383] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:1356; }","duration":"514.688814ms","start":"2026-08-14T09:23:01.332593Z","end":"2026-08-14T09:23:01.847282Z","steps":["trace[790082383] 'agreement among raft nodes before linearized reading' (duration: 514.591271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:01.847312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:01.330807Z","time spent":"516.44353ms","remote":"127.0.0.1:52150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:23:01.847366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.237509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:23:01.84739Z","caller":"traceutil/trace.go:171","msg":"trace[1536025537] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1356; }","duration":"342.328732ms","start":"2026-08-14T09:23:01.505056Z","end":"2026-08-14T09:23:01.847384Z","steps":["trace[1536025537] 'agreement among raft nodes before linearized reading' (duration: 342.291201ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:01.847411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:01.505033Z","time spent":"342.372942ms","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:23:01.84732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:01.332574Z","time spent":"514.738965ms","remote":"127.0.0.1:52160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-08-14T09:23:02.430618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.451378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:23:02.430791Z","caller":"traceutil/trace.go:171","msg":"trace[1463805455] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1418; }","duration":"155.672624ms","start":"2026-08-14T09:23:02.275104Z","end":"2026-08-14T09:23:02.430776Z","steps":["trace[1463805455] 'read index received' (duration: 82.69763ms)","trace[1463805455] 'applied index is now lower than readState.Index' (duration: 72.973984ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:23:02.430908Z","caller":"traceutil/trace.go:171","msg":"trace[389416289] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"538.354694ms","start":"2026-08-14T09:23:01.892541Z","end":"2026-08-14T09:23:02.430895Z","steps":["trace[389416289] 'process raft request' (duration: 95.553077ms)","trace[389416289] 'compare' (duration: 442.039438ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:23:02.430989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:01.892514Z","time spent":"538.433447ms","remote":"127.0.0.1:52150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:23:02.431024Z","caller":"traceutil/trace.go:171","msg":"trace[911213700] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"535.406217ms","start":"2026-08-14T09:23:01.895582Z","end":"2026-08-14T09:23:02.430988Z","steps":["trace[911213700] 'process raft request' (duration: 535.124829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:02.43125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:01.895558Z","time spent":"535.574891ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:23:02.431361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.214338ms","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-14T09:23:02.431498Z","caller":"traceutil/trace.go:171","msg":"trace[701358828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1359; }","duration":"156.357312ms","start":"2026-08-14T09:23:02.275098Z","end":"2026-08-14T09:23:02.431455Z","steps":["trace[701358828] 'agreement among raft nodes before linearized reading' (duration: 155.813568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:03.659417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.986001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:23:03.659486Z","caller":"traceutil/trace.go:171","msg":"trace[415452326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1365; }","duration":"313.154046ms","start":"2026-08-14T09:23:03.346309Z","end":"2026-08-14T09:23:03.659464Z","steps":["trace[415452326] 'range keys from in-memory index tree' (duration: 312.876839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:03.659516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:03.346286Z","time spent":"313.221728ms","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:23:04.96546Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640612084281592568,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:23:05.209093Z","caller":"traceutil/trace.go:171","msg":"trace[1826400529] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"1.289161232s","start":"2026-08-14T09:23:03.919898Z","end":"2026-08-14T09:23:05.20906Z","steps":["trace[1826400529] 'process raft request' (duration: 1.288908825s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:05.209312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:03.919872Z","time spent":"1.289338747s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:23:05.291258Z","caller":"traceutil/trace.go:171","msg":"trace[1704962032] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1426; }","duration":"826.7094ms","start":"2026-08-14T09:23:04.464513Z","end":"2026-08-14T09:23:05.291222Z","steps":["trace[1704962032] 'read index received' (duration: 744.35094ms)","trace[1704962032] 'applied index is now lower than readState.Index' (duration: 82.35725ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:23:05.291295Z","caller":"traceutil/trace.go:171","msg":"trace[2002083327] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"820.758193ms","start":"2026-08-14T09:23:04.470502Z","end":"2026-08-14T09:23:05.29126Z","steps":["trace[2002083327] 'process raft request' (duration: 820.613819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:05.291484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.959466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-14T09:23:05.291498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:04.470484Z","time spent":"820.897977ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:23:05.291526Z","caller":"traceutil/trace.go:171","msg":"trace[1207471976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1367; }","duration":"827.052238ms","start":"2026-08-14T09:23:04.464463Z","end":"2026-08-14T09:23:05.291515Z","steps":["trace[1207471976] 'agreement among raft nodes before linearized reading' (duration: 826.890984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:05.291563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:04.464444Z","time spent":"827.109759ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:23:05.291684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.105633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:23:05.292258Z","caller":"traceutil/trace.go:171","msg":"trace[1837311054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1367; }","duration":"765.712449ms","start":"2026-08-14T09:23:04.526527Z","end":"2026-08-14T09:23:05.29224Z","steps":["trace[1837311054] 'agreement among raft nodes before linearized reading' (duration: 764.981309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:05.29232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:04.526514Z","time spent":"765.791432ms","remote":"127.0.0.1:52324","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-14T09:23:05.291752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.752652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:23:05.292455Z","caller":"traceutil/trace.go:171","msg":"trace[241018618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1367; }","duration":"707.474532ms","start":"2026-08-14T09:23:04.584953Z","end":"2026-08-14T09:23:05.292428Z","steps":["trace[241018618] 'agreement among raft nodes before linearized reading' (duration: 706.709531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:05.29254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:04.584932Z","time spent":"707.581274ms","remote":"127.0.0.1:52324","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-14T09:23:05.291794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.205349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:23:05.292678Z","caller":"traceutil/trace.go:171","msg":"trace[1955441805] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1367; }","duration":"199.111224ms","start":"2026-08-14T09:23:05.093552Z","end":"2026-08-14T09:23:05.292663Z","steps":["trace[1955441805] 'agreement among raft nodes before linearized reading' (duration: 198.21815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:05.291832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.066845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:23:05.292871Z","caller":"traceutil/trace.go:171","msg":"trace[785942484] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1367; }","duration":"526.138064ms","start":"2026-08-14T09:23:04.76672Z","end":"2026-08-14T09:23:05.292858Z","steps":["trace[785942484] 'agreement among raft nodes before linearized reading' (duration: 525.091636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:05.292922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:04.7667Z","time spent":"526.206025ms","remote":"127.0.0.1:34752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:23:05.291881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.430659ms","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-14T09:23:05.293032Z","caller":"traceutil/trace.go:171","msg":"trace[1650371765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1367; }","duration":"708.633691ms","start":"2026-08-14T09:23:04.584388Z","end":"2026-08-14T09:23:05.293021Z","steps":["trace[1650371765] 'agreement among raft nodes before linearized reading' (duration: 707.394608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:05.293083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:04.584365Z","time spent":"708.696873ms","remote":"127.0.0.1:52324","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-14T09:23:05.292149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.638316ms","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-14T09:23:05.293267Z","caller":"traceutil/trace.go:171","msg":"trace[832556519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1367; }","duration":"708.784075ms","start":"2026-08-14T09:23:04.584472Z","end":"2026-08-14T09:23:05.293256Z","steps":["trace[832556519] 'agreement among raft nodes before linearized reading' (duration: 707.283175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:23:05.293308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:23:04.584462Z","time spent":"708.833346ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-14T09:26:10.387771Z","caller":"traceutil/trace.go:171","msg":"trace[66622747] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"137.333967ms","start":"2026-08-14T09:26:10.25041Z","end":"2026-08-14T09:26:10.387744Z","steps":["trace[66622747] 'process raft request' (duration: 137.185823ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:26:10.41581Z","caller":"traceutil/trace.go:171","msg":"trace[323050514] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"161.213316ms","start":"2026-08-14T09:26:10.254562Z","end":"2026-08-14T09:26:10.415775Z","steps":["trace[323050514] 'process raft request' (duration: 161.01296ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:26:10.415902Z","caller":"traceutil/trace.go:171","msg":"trace[2108551097] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"160.370844ms","start":"2026-08-14T09:26:10.255502Z","end":"2026-08-14T09:26:10.415872Z","steps":["trace[2108551097] 'process raft request' (duration: 160.167568ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:26:10.416025Z","caller":"traceutil/trace.go:171","msg":"trace[1000672034] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"160.394244ms","start":"2026-08-14T09:26:10.255624Z","end":"2026-08-14T09:26:10.416019Z","steps":["trace[1000672034] 'process raft request' (duration: 160.209939ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:26:10.415997Z","caller":"traceutil/trace.go:171","msg":"trace[708105587] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"160.304732ms","start":"2026-08-14T09:26:10.255672Z","end":"2026-08-14T09:26:10.415977Z","steps":["trace[708105587] 'process raft request' (duration: 160.189609ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:26:16.580862Z","caller":"traceutil/trace.go:171","msg":"trace[184027240] transaction","detail":"{read_only:false; response_revision:2290; number_of_response:1; }","duration":"134.610186ms","start":"2026-08-14T09:26:16.446218Z","end":"2026-08-14T09:26:16.580828Z","steps":["trace[184027240] 'process raft request' (duration: 114.994371ms)","trace[184027240] 'compare' (duration: 19.316228ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:26:16.596343Z","caller":"traceutil/trace.go:171","msg":"trace[1972092157] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"148.84239ms","start":"2026-08-14T09:26:16.447476Z","end":"2026-08-14T09:26:16.596319Z","steps":["trace[1972092157] 'process raft request' (duration: 148.768828ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:26:16.596347Z","caller":"traceutil/trace.go:171","msg":"trace[852321488] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"149.280873ms","start":"2026-08-14T09:26:16.447033Z","end":"2026-08-14T09:26:16.596314Z","steps":["trace[852321488] 'process raft request' (duration: 149.107928ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:26:16.596657Z","caller":"traceutil/trace.go:171","msg":"trace[838917639] transaction","detail":"{read_only:false; response_revision:2293; number_of_response:1; }","duration":"148.769308ms","start":"2026-08-14T09:26:16.447869Z","end":"2026-08-14T09:26:16.596638Z","steps":["trace[838917639] 'process raft request' (duration: 148.412649ms)"],"step_count":1}