{"level":"warn","ts":"2026-07-09T16:42:50.43127Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T16:42:50.431391Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-09T16:42:50.43148Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T16:42:50.431495Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-07-09T16:42:50.431534Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T16:42:50.432418Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-07-09T16:42:50.432514Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-09T16:42:50.435238Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.13572ms"} {"level":"info","ts":"2026-07-09T16:42:50.441128Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-07-09T16:42:50.441375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T16:42:50.441433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-07-09T16:42:50.441451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T16:42:50.441465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-07-09T16:42:50.441517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-07-09T16:42:50.446237Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T16:42:50.447687Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T16:42:50.449562Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-09T16:42:50.45277Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T16:42:50.453171Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T16:42:50.45331Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T16:42:50.453363Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T16:42:50.453373Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T16:42:50.455809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-07-09T16:42:50.455943Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-07-09T16:42:50.458292Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T16:42:50.458495Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T16:42:50.45853Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T16:42:50.459059Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-07-09T16:42:50.459131Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-07-09T16:42:51.142271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T16:42:51.142363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T16:42:51.142387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-07-09T16:42:51.142406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-07-09T16:42:51.142417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-07-09T16:42:51.142452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-07-09T16:42:51.142466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-07-09T16:42:51.143735Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:42:51.14476Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T16:42:51.144767Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:42:51.144827Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:42:51.145126Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T16:42:51.145222Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T16:42:51.146432Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:42:51.146552Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:42:51.146599Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:42:51.147039Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T16:42:51.153165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-07-09T16:42:55.361014Z","caller":"traceutil/trace.go:171","msg":"trace[894994077] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"657.114136ms","start":"2026-07-09T16:42:54.703874Z","end":"2026-07-09T16:42:55.360989Z","steps":["trace[894994077] 'process raft request' (duration: 656.979043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:55.361662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:54.703859Z","time spent":"657.28501ms","remote":"127.0.0.1:33316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:42:55.86631Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955942747261742,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:42:56.070099Z","caller":"traceutil/trace.go:171","msg":"trace[340437401] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:250; }","duration":"704.772258ms","start":"2026-07-09T16:42:55.3653Z","end":"2026-07-09T16:42:56.070072Z","steps":["trace[340437401] 'read index received' (duration: 646.107861ms)","trace[340437401] 'applied index is now lower than readState.Index' (duration: 58.663387ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:42:56.07038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.972973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-oeiifl\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:42:56.070409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.283929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:42:56.070451Z","caller":"traceutil/trace.go:171","msg":"trace[285903150] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-oeiifl; range_end:; response_count:0; response_revision:246; }","duration":"705.177818ms","start":"2026-07-09T16:42:55.365258Z","end":"2026-07-09T16:42:56.070436Z","steps":["trace[285903150] 'agreement among raft nodes before linearized reading' (duration: 704.966073ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:56.070479Z","caller":"traceutil/trace.go:171","msg":"trace[182896442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:246; }","duration":"152.408202ms","start":"2026-07-09T16:42:55.918054Z","end":"2026-07-09T16:42:56.070462Z","steps":["trace[182896442] 'agreement among raft nodes before linearized reading' (duration: 152.220118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:56.070493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:55.365237Z","time spent":"705.244449ms","remote":"127.0.0.1:33234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-oeiifl\" "} {"level":"info","ts":"2026-07-09T16:42:56.070384Z","caller":"traceutil/trace.go:171","msg":"trace[593384078] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"801.36098ms","start":"2026-07-09T16:42:55.268789Z","end":"2026-07-09T16:42:56.070149Z","steps":["trace[593384078] 'process raft request' (duration: 742.545139ms)","trace[593384078] 'compare' (duration: 58.580056ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:42:56.071965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:55.268762Z","time spent":"803.07217ms","remote":"127.0.0.1:33330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:42:57.059036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.728752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:42:57.059133Z","caller":"traceutil/trace.go:171","msg":"trace[1433728746] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:252; }","duration":"927.421669ms","start":"2026-07-09T16:42:56.131695Z","end":"2026-07-09T16:42:57.059117Z","steps":["trace[1433728746] 'read index received' (duration: 426.343259ms)","trace[1433728746] 'applied index is now lower than readState.Index' (duration: 501.07711ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:42:57.059269Z","caller":"traceutil/trace.go:171","msg":"trace[211661191] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"983.071976ms","start":"2026-07-09T16:42:56.076183Z","end":"2026-07-09T16:42:57.059255Z","steps":["trace[211661191] 'process raft request' (duration: 481.928515ms)","trace[211661191] 'compare' (duration: 500.133248ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:42:57.059379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:56.076159Z","time spent":"983.190909ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:42:57.059611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.914561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-oeiifl\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-07-09T16:42:57.059643Z","caller":"traceutil/trace.go:171","msg":"trace[2094445124] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-oeiifl; range_end:; response_count:1; response_revision:248; }","duration":"927.982853ms","start":"2026-07-09T16:42:56.13165Z","end":"2026-07-09T16:42:57.059633Z","steps":["trace[2094445124] 'agreement among raft nodes before linearized reading' (duration: 927.896361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:57.059672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:56.131634Z","time spent":"928.031484ms","remote":"127.0.0.1:33234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":563,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-oeiifl\" "} {"level":"warn","ts":"2026-07-09T16:42:57.059688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.815638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:42:57.05976Z","caller":"traceutil/trace.go:171","msg":"trace[324188302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:248; }","duration":"676.93212ms","start":"2026-07-09T16:42:56.382809Z","end":"2026-07-09T16:42:57.059741Z","steps":["trace[324188302] 'agreement among raft nodes before linearized reading' (duration: 676.802737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:57.059798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:56.382791Z","time spent":"676.999401ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:42:57.059814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.738727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:42:57.059844Z","caller":"traceutil/trace.go:171","msg":"trace[1459624243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:248; }","duration":"311.809109ms","start":"2026-07-09T16:42:56.748027Z","end":"2026-07-09T16:42:57.059836Z","steps":["trace[1459624243] 'agreement among raft nodes before linearized reading' (duration: 311.757737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:57.059869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:56.748007Z","time spent":"311.85826ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:42:57.059929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.431009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:42:57.06Z","caller":"traceutil/trace.go:171","msg":"trace[1490493266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:248; }","duration":"338.556762ms","start":"2026-07-09T16:42:56.721424Z","end":"2026-07-09T16:42:57.05998Z","steps":["trace[1490493266] 'agreement among raft nodes before linearized reading' (duration: 338.438999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:57.060047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:56.721404Z","time spent":"338.634874ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:43:27.438945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.687679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:43:27.439068Z","caller":"traceutil/trace.go:171","msg":"trace[100210679] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"169.463289ms","start":"2026-07-09T16:43:27.269585Z","end":"2026-07-09T16:43:27.439048Z","steps":["trace[100210679] 'read index received' (duration: 7.529794ms)","trace[100210679] 'applied index is now lower than readState.Index' (duration: 161.932365ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:27.439082Z","caller":"traceutil/trace.go:171","msg":"trace[888470116] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"182.811801ms","start":"2026-07-09T16:43:27.256237Z","end":"2026-07-09T16:43:27.439049Z","steps":["trace[888470116] 'process raft request' (duration: 20.935588ms)","trace[888470116] 'compare' (duration: 161.523385ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:27.439223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.636554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T16:43:27.43928Z","caller":"traceutil/trace.go:171","msg":"trace[1936839891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"169.736007ms","start":"2026-07-09T16:43:27.26951Z","end":"2026-07-09T16:43:27.439246Z","steps":["trace[1936839891] 'agreement among raft nodes before linearized reading' (duration: 169.631464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:27.439474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.519862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T16:43:27.43969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.236107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:43:27.439723Z","caller":"traceutil/trace.go:171","msg":"trace[290623808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"106.295028ms","start":"2026-07-09T16:43:27.333418Z","end":"2026-07-09T16:43:27.439713Z","steps":["trace[290623808] 'agreement among raft nodes before linearized reading' (duration: 106.233867ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:27.439519Z","caller":"traceutil/trace.go:171","msg":"trace[1861820259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:494; }","duration":"121.595054ms","start":"2026-07-09T16:43:27.317909Z","end":"2026-07-09T16:43:27.439504Z","steps":["trace[1861820259] 'agreement among raft nodes before linearized reading' (duration: 121.499811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:30.504554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.008374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:43:30.504684Z","caller":"traceutil/trace.go:171","msg":"trace[987509049] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"609.902186ms","start":"2026-07-09T16:43:29.894766Z","end":"2026-07-09T16:43:30.504668Z","steps":["trace[987509049] 'compare' (duration: 601.724986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:30.504743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:29.894745Z","time spent":"609.970078ms","remote":"127.0.0.1:33172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:43:30.825202Z","caller":"traceutil/trace.go:171","msg":"trace[1291768269] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"812.561411ms","start":"2026-07-09T16:43:30.012613Z","end":"2026-07-09T16:43:30.825175Z","steps":["trace[1291768269] 'process raft request' (duration: 804.729468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:30.825379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:30.012579Z","time spent":"812.719395ms","remote":"127.0.0.1:33172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:43:30.825716Z","caller":"traceutil/trace.go:171","msg":"trace[1772594135] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"306.204977ms","start":"2026-07-09T16:43:30.519479Z","end":"2026-07-09T16:43:30.825684Z","steps":["trace[1772594135] 'process raft request' (duration: 305.563933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:30.825935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:30.519456Z","time spent":"306.33971ms","remote":"127.0.0.1:33172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20851,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:43:33.367568Z","caller":"traceutil/trace.go:171","msg":"trace[516355166] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"490.202827ms","start":"2026-07-09T16:43:32.877347Z","end":"2026-07-09T16:43:33.36755Z","steps":["trace[516355166] 'process raft request' (duration: 490.101194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:33.367683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:32.877335Z","time spent":"490.303509ms","remote":"127.0.0.1:33250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:43:33.795563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.870818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:43:33.795636Z","caller":"traceutil/trace.go:171","msg":"trace[419621644] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"686.003221ms","start":"2026-07-09T16:43:33.10962Z","end":"2026-07-09T16:43:33.795623Z","steps":["trace[419621644] 'process raft request' (duration: 568.01494ms)","trace[419621644] 'compare' (duration: 117.710664ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:33.795668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:33.109603Z","time spent":"686.050252ms","remote":"127.0.0.1:33396","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-07-09T16:43:33.797384Z","caller":"traceutil/trace.go:171","msg":"trace[1188185517] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:628; }","duration":"258.821594ms","start":"2026-07-09T16:43:33.538504Z","end":"2026-07-09T16:43:33.797326Z","steps":["trace[1188185517] 'read index received' (duration: 139.153624ms)","trace[1188185517] 'applied index is now lower than readState.Index' (duration: 119.66681ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:33.797425Z","caller":"traceutil/trace.go:171","msg":"trace[356383324] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"425.652732ms","start":"2026-07-09T16:43:33.371763Z","end":"2026-07-09T16:43:33.797416Z","steps":["trace[356383324] 'process raft request' (duration: 425.509869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:33.797524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.025258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T16:43:33.797459Z","caller":"traceutil/trace.go:171","msg":"trace[682347114] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"425.770324ms","start":"2026-07-09T16:43:33.371632Z","end":"2026-07-09T16:43:33.797402Z","steps":["trace[682347114] 'process raft request' (duration: 425.552639ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:33.797547Z","caller":"traceutil/trace.go:171","msg":"trace[2055001499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"259.08574ms","start":"2026-07-09T16:43:33.538456Z","end":"2026-07-09T16:43:33.797542Z","steps":["trace[2055001499] 'agreement among raft nodes before linearized reading' (duration: 258.995228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:33.79755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:33.371758Z","time spent":"425.728814ms","remote":"127.0.0.1:33354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:43:33.797653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:33.371613Z","time spent":"425.950389ms","remote":"127.0.0.1:33236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:43:33.809619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.607887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:43:33.809687Z","caller":"traceutil/trace.go:171","msg":"trace[985447441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"267.71068ms","start":"2026-07-09T16:43:33.541959Z","end":"2026-07-09T16:43:33.80967Z","steps":["trace[985447441] 'agreement among raft nodes before linearized reading' (duration: 267.477345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:33.810016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.814789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T16:43:33.810074Z","caller":"traceutil/trace.go:171","msg":"trace[1465887563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"260.894631ms","start":"2026-07-09T16:43:33.549163Z","end":"2026-07-09T16:43:33.810058Z","steps":["trace[1465887563] 'agreement among raft nodes before linearized reading' (duration: 260.774868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:33.81007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.946942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:43:33.810164Z","caller":"traceutil/trace.go:171","msg":"trace[1676500211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"264.070466ms","start":"2026-07-09T16:43:33.546069Z","end":"2026-07-09T16:43:33.810139Z","steps":["trace[1676500211] 'agreement among raft nodes before linearized reading' (duration: 263.773699ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:37.466625Z","caller":"traceutil/trace.go:171","msg":"trace[138219230] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"228.18644ms","start":"2026-07-09T16:43:37.238416Z","end":"2026-07-09T16:43:37.466603Z","steps":["trace[138219230] 'process raft request' (duration: 223.204044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:38.333304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955942747262588,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:43:38.403822Z","caller":"traceutil/trace.go:171","msg":"trace[40023295] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"571.439501ms","start":"2026-07-09T16:43:37.832358Z","end":"2026-07-09T16:43:38.403797Z","steps":["trace[40023295] 'read index received' (duration: 571.237166ms)","trace[40023295] 'applied index is now lower than readState.Index' (duration: 201.335µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:38.403965Z","caller":"traceutil/trace.go:171","msg":"trace[1960991850] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"874.773792ms","start":"2026-07-09T16:43:37.529139Z","end":"2026-07-09T16:43:38.403913Z","steps":["trace[1960991850] 'process raft request' (duration: 874.513226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:38.404102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.685366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T16:43:38.404135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:37.529119Z","time spent":"874.938515ms","remote":"127.0.0.1:33380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:43:38.404151Z","caller":"traceutil/trace.go:171","msg":"trace[992863212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"571.785879ms","start":"2026-07-09T16:43:37.832351Z","end":"2026-07-09T16:43:38.404137Z","steps":["trace[992863212] 'agreement among raft nodes before linearized reading' (duration: 571.587164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:38.404133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.798506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T16:43:38.404198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:37.832334Z","time spent":"571.85587ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T16:43:38.40421Z","caller":"traceutil/trace.go:171","msg":"trace[1939622474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"570.893538ms","start":"2026-07-09T16:43:37.833298Z","end":"2026-07-09T16:43:38.404191Z","steps":["trace[1939622474] 'agreement among raft nodes before linearized reading' (duration: 570.698044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:38.404203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.741258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T16:43:38.404244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:37.833288Z","time spent":"570.948589ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T16:43:38.404293Z","caller":"traceutil/trace.go:171","msg":"trace[1952681640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"571.951053ms","start":"2026-07-09T16:43:37.832316Z","end":"2026-07-09T16:43:38.404267Z","steps":["trace[1952681640] 'agreement among raft nodes before linearized reading' (duration: 571.676677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:38.40421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.805429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-09T16:43:38.404364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:37.832297Z","time spent":"572.049335ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T16:43:38.404459Z","caller":"traceutil/trace.go:171","msg":"trace[175598813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"572.082286ms","start":"2026-07-09T16:43:37.832316Z","end":"2026-07-09T16:43:38.404398Z","steps":["trace[175598813] 'agreement among raft nodes before linearized reading' (duration: 571.626865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:38.404642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:37.832296Z","time spent":"572.222669ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:43:38.930029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.520494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:43:38.930165Z","caller":"traceutil/trace.go:171","msg":"trace[759060802] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"520.443142ms","start":"2026-07-09T16:43:38.4097Z","end":"2026-07-09T16:43:38.930143Z","steps":["trace[759060802] 'process raft request' (duration: 301.687793ms)","trace[759060802] 'compare' (duration: 218.086384ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:38.930237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:38.409643Z","time spent":"520.564325ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:43:40.533909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.390991424s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:43:40.534493Z","caller":"traceutil/trace.go:171","msg":"trace[1649472907] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"2.124590916s","start":"2026-07-09T16:43:38.409871Z","end":"2026-07-09T16:43:40.534462Z","steps":["trace[1649472907] 'process raft request' (duration: 732.942346ms)","trace[1649472907] 'compare' (duration: 1.390737258s)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:40.534516Z","caller":"traceutil/trace.go:171","msg":"trace[792346620] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"2.124114025s","start":"2026-07-09T16:43:38.410382Z","end":"2026-07-09T16:43:40.534496Z","steps":["trace[792346620] 'process raft request' (duration: 2.123613723s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:40.534624Z","caller":"traceutil/trace.go:171","msg":"trace[671037641] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"2.123357227s","start":"2026-07-09T16:43:38.411248Z","end":"2026-07-09T16:43:40.534605Z","steps":["trace[671037641] 'process raft request' (duration: 2.122821624s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:40.534658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:38.40984Z","time spent":"2.124712598s","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:43:40.534702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:38.410364Z","time spent":"2.124284649s","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:43:40.534742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:38.411239Z","time spent":"2.123442748s","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:43:40.534748Z","caller":"traceutil/trace.go:171","msg":"trace[557785224] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.740059631s","start":"2026-07-09T16:43:38.794671Z","end":"2026-07-09T16:43:40.53473Z","steps":["trace[557785224] 'process raft request' (duration: 1.739435737s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:40.534807Z","caller":"traceutil/trace.go:171","msg":"trace[726173691] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:660; }","duration":"2.12310849s","start":"2026-07-09T16:43:38.411665Z","end":"2026-07-09T16:43:40.534773Z","steps":["trace[726173691] 'read index received' (duration: 299.790368ms)","trace[726173691] 'applied index is now lower than readState.Index' (duration: 1.823308682s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:40.534835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:38.794645Z","time spent":"1.740144934s","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:43:40.534957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.123297675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-09T16:43:40.535019Z","caller":"traceutil/trace.go:171","msg":"trace[1294661968] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:643; }","duration":"2.123357117s","start":"2026-07-09T16:43:38.41165Z","end":"2026-07-09T16:43:40.535007Z","steps":["trace[1294661968] 'agreement among raft nodes before linearized reading' (duration: 2.123260665s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:40.535057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:38.411605Z","time spent":"2.123444599s","remote":"127.0.0.1:33354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-07-09T16:43:40.721207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00018032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-09T16:43:40.721298Z","caller":"traceutil/trace.go:171","msg":"trace[165112384] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000336774s","start":"2026-07-09T16:43:38.720942Z","end":"2026-07-09T16:43:40.721279Z","steps":["trace[165112384] 'agreement among raft nodes before linearized reading' (duration: 2.000216581s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:40.721397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:38.720916Z","time spent":"2.000470926s","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:43:40.7781Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.250371229s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T16:43:41.981109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.20270474s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T16:43:41.9812Z","caller":"traceutil/trace.go:171","msg":"trace[372047276] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"1.446297271s","start":"2026-07-09T16:43:40.534887Z","end":"2026-07-09T16:43:41.981184Z","steps":["trace[372047276] 'read index received' (duration: 243.399427ms)","trace[372047276] 'applied index is now lower than readState.Index' (duration: 1.202896494s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:41.981319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438572781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:43:41.981356Z","caller":"traceutil/trace.go:171","msg":"trace[1194437884] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:643; }","duration":"1.438642653s","start":"2026-07-09T16:43:40.542702Z","end":"2026-07-09T16:43:41.981345Z","steps":["trace[1194437884] 'agreement among raft nodes before linearized reading' (duration: 1.438574461s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:41.981391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.542688Z","time spent":"1.438694773s","remote":"127.0.0.1:33292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-07-09T16:43:41.981409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.140505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:43:41.981438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25941155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:43:41.981496Z","caller":"traceutil/trace.go:171","msg":"trace[1996269750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"223.296739ms","start":"2026-07-09T16:43:41.758181Z","end":"2026-07-09T16:43:41.981478Z","steps":["trace[1996269750] 'agreement among raft nodes before linearized reading' (duration: 223.116015ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:41.981564Z","caller":"traceutil/trace.go:171","msg":"trace[759959454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"1.259576993s","start":"2026-07-09T16:43:40.721968Z","end":"2026-07-09T16:43:41.981545Z","steps":["trace[759959454] 'agreement among raft nodes before linearized reading' (duration: 1.259388179s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:41.981692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.721953Z","time spent":"1.259725887s","remote":"127.0.0.1:33122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:43:41.981892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047800073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T16:43:41.981975Z","caller":"traceutil/trace.go:171","msg":"trace[336299912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"1.047988468s","start":"2026-07-09T16:43:40.933969Z","end":"2026-07-09T16:43:41.981958Z","steps":["trace[336299912] 'agreement among raft nodes before linearized reading' (duration: 1.047370814s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:41.982051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.933937Z","time spent":"1.048102311s","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T16:43:42.111873Z","caller":"traceutil/trace.go:171","msg":"trace[742506673] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"125.493291ms","start":"2026-07-09T16:43:41.986358Z","end":"2026-07-09T16:43:42.111851Z","steps":["trace[742506673] 'process raft request' (duration: 125.337788ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:42.112979Z","caller":"traceutil/trace.go:171","msg":"trace[1800620273] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:666; }","duration":"123.2781ms","start":"2026-07-09T16:43:41.989677Z","end":"2026-07-09T16:43:42.112955Z","steps":["trace[1800620273] 'read index received' (duration: 121.925459ms)","trace[1800620273] 'applied index is now lower than readState.Index' (duration: 1.351471ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:42.113144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.440453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:43:42.113175Z","caller":"traceutil/trace.go:171","msg":"trace[939857720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"123.509235ms","start":"2026-07-09T16:43:41.989656Z","end":"2026-07-09T16:43:42.113165Z","steps":["trace[939857720] 'agreement among raft nodes before linearized reading' (duration: 123.416953ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:42.113408Z","caller":"traceutil/trace.go:171","msg":"trace[1339128526] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"125.313407ms","start":"2026-07-09T16:43:41.988078Z","end":"2026-07-09T16:43:42.113391Z","steps":["trace[1339128526] 'process raft request' (duration: 124.718903ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:42.11356Z","caller":"traceutil/trace.go:171","msg":"trace[1068338816] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"126.649138ms","start":"2026-07-09T16:43:41.986867Z","end":"2026-07-09T16:43:42.113516Z","steps":["trace[1068338816] 'process raft request' (duration: 125.791739ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:42.237189Z","caller":"traceutil/trace.go:171","msg":"trace[573047189] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"118.496978ms","start":"2026-07-09T16:43:42.118667Z","end":"2026-07-09T16:43:42.237164Z","steps":["trace[573047189] 'process raft request' (duration: 118.268633ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:42.237209Z","caller":"traceutil/trace.go:171","msg":"trace[679734598] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"117.913975ms","start":"2026-07-09T16:43:42.119272Z","end":"2026-07-09T16:43:42.237186Z","steps":["trace[679734598] 'process raft request' (duration: 117.862993ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:42.237263Z","caller":"traceutil/trace.go:171","msg":"trace[354740472] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"118.499309ms","start":"2026-07-09T16:43:42.118742Z","end":"2026-07-09T16:43:42.237241Z","steps":["trace[354740472] 'process raft request' (duration: 118.331675ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:54.643921Z","caller":"traceutil/trace.go:171","msg":"trace[650939197] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"259.709087ms","start":"2026-07-09T16:43:54.384184Z","end":"2026-07-09T16:43:54.643893Z","steps":["trace[650939197] 'read index received' (duration: 259.41894ms)","trace[650939197] 'applied index is now lower than readState.Index' (duration: 288.907µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:54.644053Z","caller":"traceutil/trace.go:171","msg":"trace[2014475973] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"287.322309ms","start":"2026-07-09T16:43:54.356705Z","end":"2026-07-09T16:43:54.644027Z","steps":["trace[2014475973] 'process raft request' (duration: 286.957291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:54.644126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.923182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:43:54.644622Z","caller":"traceutil/trace.go:171","msg":"trace[1158055009] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:790; }","duration":"260.466535ms","start":"2026-07-09T16:43:54.384135Z","end":"2026-07-09T16:43:54.644601Z","steps":["trace[1158055009] 'agreement among raft nodes before linearized reading' (duration: 259.930112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:57.45197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.881908ms","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-07-09T16:43:57.452434Z","caller":"traceutil/trace.go:171","msg":"trace[686094325] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:806; }","duration":"238.412631ms","start":"2026-07-09T16:43:57.214Z","end":"2026-07-09T16:43:57.452413Z","steps":["trace[686094325] 'count revisions from in-memory index tree' (duration: 237.772217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:57.452266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.42494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kqmtt\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-09T16:43:57.452825Z","caller":"traceutil/trace.go:171","msg":"trace[489796956] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kqmtt; range_end:; response_count:1; response_revision:806; }","duration":"135.018283ms","start":"2026-07-09T16:43:57.317771Z","end":"2026-07-09T16:43:57.452789Z","steps":["trace[489796956] 'range keys from in-memory index tree' (duration: 134.220866ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:58.207747Z","caller":"traceutil/trace.go:171","msg":"trace[254780141] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"250.225666ms","start":"2026-07-09T16:43:57.957496Z","end":"2026-07-09T16:43:58.207722Z","steps":["trace[254780141] 'read index received' (duration: 249.98448ms)","trace[254780141] 'applied index is now lower than readState.Index' (duration: 239.876µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:58.207923Z","caller":"traceutil/trace.go:171","msg":"trace[1794027648] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"748.653919ms","start":"2026-07-09T16:43:57.459259Z","end":"2026-07-09T16:43:58.207913Z","steps":["trace[1794027648] 'process raft request' (duration: 748.299251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:58.208039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:57.459237Z","time spent":"748.717301ms","remote":"127.0.0.1:33330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:43:58.208263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.771289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T16:43:58.208294Z","caller":"traceutil/trace.go:171","msg":"trace[1778958901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:807; }","duration":"250.837511ms","start":"2026-07-09T16:43:57.957447Z","end":"2026-07-09T16:43:58.208284Z","steps":["trace[1778958901] 'agreement among raft nodes before linearized reading' (duration: 250.719178ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:32.716949Z","caller":"traceutil/trace.go:171","msg":"trace[1546717830] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"326.732767ms","start":"2026-07-09T16:44:32.390186Z","end":"2026-07-09T16:44:32.716919Z","steps":["trace[1546717830] 'process raft request' (duration: 326.558293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:32.717121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:32.390168Z","time spent":"326.86826ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:33.31785Z","caller":"traceutil/trace.go:171","msg":"trace[110178479] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1053; }","duration":"883.742415ms","start":"2026-07-09T16:44:32.434055Z","end":"2026-07-09T16:44:33.317797Z","steps":["trace[110178479] 'read index received' (duration: 282.766363ms)","trace[110178479] 'applied index is now lower than readState.Index' (duration: 600.975252ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:33.317941Z","caller":"traceutil/trace.go:171","msg":"trace[178945056] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"734.393789ms","start":"2026-07-09T16:44:32.58354Z","end":"2026-07-09T16:44:33.317934Z","steps":["trace[178945056] 'process raft request' (duration: 734.219925ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:33.317966Z","caller":"traceutil/trace.go:171","msg":"trace[818981491] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"927.203676ms","start":"2026-07-09T16:44:32.39073Z","end":"2026-07-09T16:44:33.317934Z","steps":["trace[818981491] 'process raft request' (duration: 926.907039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:33.318091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:32.390719Z","time spent":"927.321109ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:44:33.318112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.059832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:33.318147Z","caller":"traceutil/trace.go:171","msg":"trace[537025779] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1020; }","duration":"884.136444ms","start":"2026-07-09T16:44:32.433998Z","end":"2026-07-09T16:44:33.318135Z","steps":["trace[537025779] 'agreement among raft nodes before linearized reading' (duration: 884.065183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:33.318176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:32.43398Z","time spent":"884.189725ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:44:33.318295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.925528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T16:44:33.318444Z","caller":"traceutil/trace.go:171","msg":"trace[259817103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"466.121973ms","start":"2026-07-09T16:44:32.852297Z","end":"2026-07-09T16:44:33.318419Z","steps":["trace[259817103] 'agreement among raft nodes before linearized reading' (duration: 465.789765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:33.318498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:32.85228Z","time spent":"466.207214ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:44:33.318511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.868712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:44:33.318539Z","caller":"traceutil/trace.go:171","msg":"trace[697685565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"339.919853ms","start":"2026-07-09T16:44:32.978609Z","end":"2026-07-09T16:44:33.318529Z","steps":["trace[697685565] 'agreement among raft nodes before linearized reading' (duration: 339.827851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:33.318565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:32.978592Z","time spent":"339.967324ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:44:33.318576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.792223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:44:33.318618Z","caller":"traceutil/trace.go:171","msg":"trace[864641871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"425.880875ms","start":"2026-07-09T16:44:32.892724Z","end":"2026-07-09T16:44:33.318604Z","steps":["trace[864641871] 'agreement among raft nodes before linearized reading' (duration: 425.768342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:33.318657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:32.892708Z","time spent":"425.941866ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:44:33.318697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:32.583517Z","time spent":"734.4562ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:44:33.885013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.312214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:44:33.885439Z","caller":"traceutil/trace.go:171","msg":"trace[304425228] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"563.123101ms","start":"2026-07-09T16:44:33.322291Z","end":"2026-07-09T16:44:33.885414Z","steps":["trace[304425228] 'process raft request' (duration: 191.330635ms)","trace[304425228] 'compare' (duration: 371.198262ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:33.88552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:33.322275Z","time spent":"563.209892ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:33.886133Z","caller":"traceutil/trace.go:171","msg":"trace[836612507] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"562.743441ms","start":"2026-07-09T16:44:33.323381Z","end":"2026-07-09T16:44:33.886124Z","steps":["trace[836612507] 'process raft request' (duration: 562.659879ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:33.886141Z","caller":"traceutil/trace.go:171","msg":"trace[482530208] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"563.653913ms","start":"2026-07-09T16:44:33.322459Z","end":"2026-07-09T16:44:33.886113Z","steps":["trace[482530208] 'process raft request' (duration: 562.910166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:33.886206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:33.323372Z","time spent":"562.797542ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:44:33.886283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:33.322453Z","time spent":"563.743305ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:33.886087Z","caller":"traceutil/trace.go:171","msg":"trace[532167915] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1056; }","duration":"165.824581ms","start":"2026-07-09T16:44:33.720251Z","end":"2026-07-09T16:44:33.886076Z","steps":["trace[532167915] 'read index received' (duration: 144.616407ms)","trace[532167915] 'applied index is now lower than readState.Index' (duration: 21.207204ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:33.886373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.121798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:33.88641Z","caller":"traceutil/trace.go:171","msg":"trace[1908365260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"166.18612ms","start":"2026-07-09T16:44:33.720215Z","end":"2026-07-09T16:44:33.886401Z","steps":["trace[1908365260] 'agreement among raft nodes before linearized reading' (duration: 166.089788ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:54.792933Z","caller":"traceutil/trace.go:171","msg":"trace[299585017] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"418.188358ms","start":"2026-07-09T16:44:54.374712Z","end":"2026-07-09T16:44:54.7929Z","steps":["trace[299585017] 'process raft request' (duration: 386.885486ms)","trace[299585017] 'compare' (duration: 31.056426ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:54.793084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:54.374697Z","time spent":"418.302972ms","remote":"127.0.0.1:33156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:55.042953Z","caller":"traceutil/trace.go:171","msg":"trace[1338637685] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"169.559741ms","start":"2026-07-09T16:44:54.873349Z","end":"2026-07-09T16:44:55.042909Z","steps":["trace[1338637685] 'process raft request' (duration: 156.219639ms)","trace[1338637685] 'compare' (duration: 13.160788ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:56.268052Z","caller":"traceutil/trace.go:171","msg":"trace[193488883] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"278.775141ms","start":"2026-07-09T16:44:55.989249Z","end":"2026-07-09T16:44:56.268024Z","steps":["trace[193488883] 'process raft request' (duration: 278.617878ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:56.615496Z","caller":"traceutil/trace.go:171","msg":"trace[17527727] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1135; }","duration":"623.649828ms","start":"2026-07-09T16:44:55.99182Z","end":"2026-07-09T16:44:56.61547Z","steps":["trace[17527727] 'read index received' (duration: 276.064169ms)","trace[17527727] 'applied index is now lower than readState.Index' (duration: 347.584469ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:56.615624Z","caller":"traceutil/trace.go:171","msg":"trace[185677804] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"625.620793ms","start":"2026-07-09T16:44:55.98999Z","end":"2026-07-09T16:44:56.61561Z","steps":["trace[185677804] 'process raft request' (duration: 564.26092ms)","trace[185677804] 'compare' (duration: 61.108497ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:56.615682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.469992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:44:56.615717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:55.989981Z","time spent":"625.669774ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:56.615733Z","caller":"traceutil/trace.go:171","msg":"trace[1940985490] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1096; }","duration":"188.572095ms","start":"2026-07-09T16:44:56.427146Z","end":"2026-07-09T16:44:56.615718Z","steps":["trace[1940985490] 'agreement among raft nodes before linearized reading' (duration: 188.475412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:56.615795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.953325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:44:56.615868Z","caller":"traceutil/trace.go:171","msg":"trace[78310369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1096; }","duration":"624.045507ms","start":"2026-07-09T16:44:55.991802Z","end":"2026-07-09T16:44:56.615848Z","steps":["trace[78310369] 'agreement among raft nodes before linearized reading' (duration: 623.867813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:56.615935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:55.991796Z","time spent":"624.131199ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T16:44:58.412617Z","caller":"traceutil/trace.go:171","msg":"trace[565086519] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"135.909375ms","start":"2026-07-09T16:44:58.276689Z","end":"2026-07-09T16:44:58.412598Z","steps":["trace[565086519] 'process raft request' (duration: 135.766172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:58.886761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.168736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:58.88684Z","caller":"traceutil/trace.go:171","msg":"trace[333742817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"164.318609ms","start":"2026-07-09T16:44:58.722507Z","end":"2026-07-09T16:44:58.886826Z","steps":["trace[333742817] 'range keys from in-memory index tree' (duration: 164.075274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:58.887082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.621871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:44:58.887145Z","caller":"traceutil/trace.go:171","msg":"trace[1718030164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1101; }","duration":"234.746773ms","start":"2026-07-09T16:44:58.652384Z","end":"2026-07-09T16:44:58.887131Z","steps":["trace[1718030164] 'range keys from in-memory index tree' (duration: 234.439156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:58.887162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.309414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T16:44:58.887219Z","caller":"traceutil/trace.go:171","msg":"trace[1043594330] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1101; }","duration":"267.406076ms","start":"2026-07-09T16:44:58.619798Z","end":"2026-07-09T16:44:58.887204Z","steps":["trace[1043594330] 'range keys from in-memory index tree' (duration: 267.138199ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:59.036944Z","caller":"traceutil/trace.go:171","msg":"trace[2121718417] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"145.374705ms","start":"2026-07-09T16:44:58.891529Z","end":"2026-07-09T16:44:59.036904Z","steps":["trace[2121718417] 'process raft request' (duration: 145.216172ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:59.054455Z","caller":"traceutil/trace.go:171","msg":"trace[1607046728] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"161.240167ms","start":"2026-07-09T16:44:58.893188Z","end":"2026-07-09T16:44:59.054429Z","steps":["trace[1607046728] 'process raft request' (duration: 161.079224ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:15.25753Z","caller":"traceutil/trace.go:171","msg":"trace[813032895] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1252; }","duration":"124.246863ms","start":"2026-07-09T16:45:15.133265Z","end":"2026-07-09T16:45:15.257512Z","steps":["trace[813032895] 'read index received' (duration: 124.183941ms)","trace[813032895] 'applied index is now lower than readState.Index' (duration: 62.212µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:15.257678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.395186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T16:45:15.257696Z","caller":"traceutil/trace.go:171","msg":"trace[1767344839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1206; }","duration":"124.449678ms","start":"2026-07-09T16:45:15.13324Z","end":"2026-07-09T16:45:15.25769Z","steps":["trace[1767344839] 'agreement among raft nodes before linearized reading' (duration: 124.342375ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:15.257777Z","caller":"traceutil/trace.go:171","msg":"trace[1765910937] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"126.972687ms","start":"2026-07-09T16:45:15.130771Z","end":"2026-07-09T16:45:15.257743Z","steps":["trace[1765910937] 'process raft request' (duration: 126.625919ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:15.696644Z","caller":"traceutil/trace.go:171","msg":"trace[177694674] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"433.817183ms","start":"2026-07-09T16:45:15.262783Z","end":"2026-07-09T16:45:15.6966Z","steps":["trace[177694674] 'process raft request' (duration: 433.658249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:15.696784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:15.262756Z","time spent":"433.960505ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:45:15.697862Z","caller":"traceutil/trace.go:171","msg":"trace[709860074] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"434.98613ms","start":"2026-07-09T16:45:15.262846Z","end":"2026-07-09T16:45:15.697832Z","steps":["trace[709860074] 'process raft request' (duration: 434.791415ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:15.697961Z","caller":"traceutil/trace.go:171","msg":"trace[350698903] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1253; }","duration":"225.718143ms","start":"2026-07-09T16:45:15.472223Z","end":"2026-07-09T16:45:15.697941Z","steps":["trace[350698903] 'read index received' (duration: 224.160267ms)","trace[350698903] 'applied index is now lower than readState.Index' (duration: 1.556926ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:15.698051Z","caller":"traceutil/trace.go:171","msg":"trace[986595450] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"208.679215ms","start":"2026-07-09T16:45:15.489364Z","end":"2026-07-09T16:45:15.698044Z","steps":["trace[986595450] 'process raft request' (duration: 208.422249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:15.698192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.944658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:45:15.698226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.698141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T16:45:15.698305Z","caller":"traceutil/trace.go:171","msg":"trace[16027501] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1209; }","duration":"226.072781ms","start":"2026-07-09T16:45:15.472212Z","end":"2026-07-09T16:45:15.698284Z","steps":["trace[16027501] 'agreement among raft nodes before linearized reading' (duration: 225.905647ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:15.698357Z","caller":"traceutil/trace.go:171","msg":"trace[1752667605] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1209; }","duration":"159.839664ms","start":"2026-07-09T16:45:15.538473Z","end":"2026-07-09T16:45:15.698312Z","steps":["trace[1752667605] 'agreement among raft nodes before linearized reading' (duration: 159.616929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:15.698581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:15.262819Z","time spent":"435.126763ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:31.835145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955942747264001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:45:32.335353Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955942747264001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:45:32.376138Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.11992226s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:45:32.376488Z","caller":"traceutil/trace.go:171","msg":"trace[1619652139] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"1.120471003s","start":"2026-07-09T16:45:31.256Z","end":"2026-07-09T16:45:32.376471Z","steps":["trace[1619652139] 'process raft request' (duration: 1.120305779s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:32.376594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:31.255967Z","time spent":"1.120573035s","remote":"127.0.0.1:33316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:45:32.452752Z","caller":"traceutil/trace.go:171","msg":"trace[828700904] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1325; }","duration":"1.118438725s","start":"2026-07-09T16:45:31.334297Z","end":"2026-07-09T16:45:32.452736Z","steps":["trace[828700904] 'read index received' (duration: 1.042105652s)","trace[828700904] 'applied index is now lower than readState.Index' (duration: 76.332433ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:32.452836Z","caller":"traceutil/trace.go:171","msg":"trace[1402730311] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"701.799564ms","start":"2026-07-09T16:45:31.751031Z","end":"2026-07-09T16:45:32.452831Z","steps":["trace[1402730311] 'process raft request' (duration: 701.601709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:32.452903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:31.751004Z","time spent":"701.847984ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:32.453026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118740671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:45:32.453041Z","caller":"traceutil/trace.go:171","msg":"trace[530949954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1277; }","duration":"1.118755082s","start":"2026-07-09T16:45:31.334281Z","end":"2026-07-09T16:45:32.453036Z","steps":["trace[530949954] 'agreement among raft nodes before linearized reading' (duration: 1.11869282s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:32.453053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:31.334223Z","time spent":"1.118827384s","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:32.453152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.161516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:32.45321Z","caller":"traceutil/trace.go:171","msg":"trace[1357954792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"730.233167ms","start":"2026-07-09T16:45:31.722964Z","end":"2026-07-09T16:45:32.453198Z","steps":["trace[1357954792] 'agreement among raft nodes before linearized reading' (duration: 730.140695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:32.453238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:31.722912Z","time spent":"730.320229ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:45:32.453289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.485078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T16:45:32.453308Z","caller":"traceutil/trace.go:171","msg":"trace[357598815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1277; }","duration":"672.54921ms","start":"2026-07-09T16:45:31.780754Z","end":"2026-07-09T16:45:32.453303Z","steps":["trace[357598815] 'agreement among raft nodes before linearized reading' (duration: 672.502869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:32.45332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:31.780732Z","time spent":"672.58537ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:32.453456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.852574ms","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-07-09T16:45:32.453471Z","caller":"traceutil/trace.go:171","msg":"trace[2108098757] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1277; }","duration":"293.899554ms","start":"2026-07-09T16:45:32.159567Z","end":"2026-07-09T16:45:32.453466Z","steps":["trace[2108098757] 'agreement among raft nodes before linearized reading' (duration: 293.874034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:32.45376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.327447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T16:45:32.453776Z","caller":"traceutil/trace.go:171","msg":"trace[1617285536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1277; }","duration":"679.344338ms","start":"2026-07-09T16:45:31.774427Z","end":"2026-07-09T16:45:32.453771Z","steps":["trace[1617285536] 'agreement among raft nodes before linearized reading' (duration: 679.299366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:32.453793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:31.77436Z","time spent":"679.42916ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:45:32.453876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.477628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T16:45:32.453889Z","caller":"traceutil/trace.go:171","msg":"trace[10366542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1277; }","duration":"675.512118ms","start":"2026-07-09T16:45:31.778373Z","end":"2026-07-09T16:45:32.453885Z","steps":["trace[10366542] 'agreement among raft nodes before linearized reading' (duration: 675.473277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:32.453903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:31.778362Z","time spent":"675.537439ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T16:45:32.611804Z","caller":"traceutil/trace.go:171","msg":"trace[2116153451] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"154.424808ms","start":"2026-07-09T16:45:32.457349Z","end":"2026-07-09T16:45:32.611774Z","steps":["trace[2116153451] 'process raft request' (duration: 134.581235ms)","trace[2116153451] 'compare' (duration: 19.691269ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:32.612077Z","caller":"traceutil/trace.go:171","msg":"trace[228584048] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"152.045532ms","start":"2026-07-09T16:45:32.460023Z","end":"2026-07-09T16:45:32.612069Z","steps":["trace[228584048] 'process raft request' (duration: 151.761176ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:32.61215Z","caller":"traceutil/trace.go:171","msg":"trace[136614930] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"149.660146ms","start":"2026-07-09T16:45:32.462481Z","end":"2026-07-09T16:45:32.612141Z","steps":["trace[136614930] 'process raft request' (duration: 149.326278ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:32.612131Z","caller":"traceutil/trace.go:171","msg":"trace[550920595] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"153.213549ms","start":"2026-07-09T16:45:32.458884Z","end":"2026-07-09T16:45:32.612098Z","steps":["trace[550920595] 'process raft request' (duration: 152.83041ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:32.612319Z","caller":"traceutil/trace.go:171","msg":"trace[191327518] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"152.80485ms","start":"2026-07-09T16:45:32.459504Z","end":"2026-07-09T16:45:32.612308Z","steps":["trace[191327518] 'process raft request' (duration: 152.252517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:34.990235Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955942747264025,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:45:35.116876Z","caller":"traceutil/trace.go:171","msg":"trace[809985] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"714.643082ms","start":"2026-07-09T16:45:34.402216Z","end":"2026-07-09T16:45:35.116859Z","steps":["trace[809985] 'process raft request' (duration: 714.55109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:35.116969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.402198Z","time spent":"714.726365ms","remote":"127.0.0.1:33216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:45:35.142855Z","caller":"traceutil/trace.go:171","msg":"trace[840758793] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1337; }","duration":"653.360671ms","start":"2026-07-09T16:45:34.489472Z","end":"2026-07-09T16:45:35.142833Z","steps":["trace[840758793] 'read index received' (duration: 627.312872ms)","trace[840758793] 'applied index is now lower than readState.Index' (duration: 26.046909ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:35.142934Z","caller":"traceutil/trace.go:171","msg":"trace[1173063780] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"684.933738ms","start":"2026-07-09T16:45:34.45797Z","end":"2026-07-09T16:45:35.142903Z","steps":["trace[1173063780] 'process raft request' (duration: 684.713563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:35.143095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.601956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:45:35.143153Z","caller":"traceutil/trace.go:171","msg":"trace[367538659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"653.695619ms","start":"2026-07-09T16:45:34.489449Z","end":"2026-07-09T16:45:35.143144Z","steps":["trace[367538659] 'agreement among raft nodes before linearized reading' (duration: 653.473184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:35.143179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.489432Z","time spent":"653.738479ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:35.143164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.457952Z","time spent":"685.051302ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:35.184791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.655546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:35.18485Z","caller":"traceutil/trace.go:171","msg":"trace[1613241432] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1289; }","duration":"688.81586ms","start":"2026-07-09T16:45:34.496022Z","end":"2026-07-09T16:45:35.184838Z","steps":["trace[1613241432] 'agreement among raft nodes before linearized reading' (duration: 688.633076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:35.184832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.789535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T16:45:35.184861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.39718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T16:45:35.184871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.815476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T16:45:35.184905Z","caller":"traceutil/trace.go:171","msg":"trace[1455531891] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1289; }","duration":"566.445601ms","start":"2026-07-09T16:45:34.61845Z","end":"2026-07-09T16:45:35.184896Z","steps":["trace[1455531891] 'agreement among raft nodes before linearized reading' (duration: 566.332588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:35.184911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.088826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:35.184904Z","caller":"traceutil/trace.go:171","msg":"trace[809850197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1289; }","duration":"535.938858ms","start":"2026-07-09T16:45:34.648951Z","end":"2026-07-09T16:45:35.184889Z","steps":["trace[809850197] 'agreement among raft nodes before linearized reading' (duration: 535.720833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:35.18488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.496017Z","time spent":"688.857111ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-09T16:45:35.184929Z","caller":"traceutil/trace.go:171","msg":"trace[1501152897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1289; }","duration":"463.141268ms","start":"2026-07-09T16:45:34.721782Z","end":"2026-07-09T16:45:35.184923Z","steps":["trace[1501152897] 'agreement among raft nodes before linearized reading' (duration: 463.113517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:35.184936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.648917Z","time spent":"536.01322ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T16:45:35.184912Z","caller":"traceutil/trace.go:171","msg":"trace[2090540824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1289; }","duration":"568.895768ms","start":"2026-07-09T16:45:34.616007Z","end":"2026-07-09T16:45:35.184903Z","steps":["trace[2090540824] 'agreement among raft nodes before linearized reading' (duration: 568.822197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:35.184997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.615988Z","time spent":"568.999881ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:35.184945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.72176Z","time spent":"463.182039ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:45:35.184783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.470954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T16:45:35.185099Z","caller":"traceutil/trace.go:171","msg":"trace[845567228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1289; }","duration":"567.824762ms","start":"2026-07-09T16:45:34.617261Z","end":"2026-07-09T16:45:35.185086Z","steps":["trace[845567228] 'agreement among raft nodes before linearized reading' (duration: 567.427263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:35.185119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.617244Z","time spent":"567.869394ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:45:35.184936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.618415Z","time spent":"566.515543ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T16:45:35.253623Z","caller":"traceutil/trace.go:171","msg":"trace[173492259] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"105.583387ms","start":"2026-07-09T16:45:35.148016Z","end":"2026-07-09T16:45:35.253599Z","steps":["trace[173492259] 'process raft request' (duration: 105.379542ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:35.351568Z","caller":"traceutil/trace.go:171","msg":"trace[733778514] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"163.52183ms","start":"2026-07-09T16:45:35.188015Z","end":"2026-07-09T16:45:35.351537Z","steps":["trace[733778514] 'process raft request' (duration: 163.313535ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:35.351608Z","caller":"traceutil/trace.go:171","msg":"trace[524743303] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"158.578024ms","start":"2026-07-09T16:45:35.193012Z","end":"2026-07-09T16:45:35.35159Z","steps":["trace[524743303] 'process raft request' (duration: 158.552024ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:35.351889Z","caller":"traceutil/trace.go:171","msg":"trace[1094191511] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"162.344663ms","start":"2026-07-09T16:45:35.189519Z","end":"2026-07-09T16:45:35.351864Z","steps":["trace[1094191511] 'process raft request' (duration: 161.976774ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:35.351918Z","caller":"traceutil/trace.go:171","msg":"trace[264380807] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"163.582613ms","start":"2026-07-09T16:45:35.188313Z","end":"2026-07-09T16:45:35.351896Z","steps":["trace[264380807] 'process raft request' (duration: 163.118732ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:35.351971Z","caller":"traceutil/trace.go:171","msg":"trace[2142471575] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"162.320292ms","start":"2026-07-09T16:45:35.189641Z","end":"2026-07-09T16:45:35.351962Z","steps":["trace[2142471575] 'process raft request' (duration: 161.879322ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:35.351955Z","caller":"traceutil/trace.go:171","msg":"trace[1694107385] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"162.252121ms","start":"2026-07-09T16:45:35.189697Z","end":"2026-07-09T16:45:35.351949Z","steps":["trace[1694107385] 'process raft request' (duration: 161.846521ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:45.63822Z","caller":"traceutil/trace.go:171","msg":"trace[1702802683] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"738.996522ms","start":"2026-07-09T16:45:44.899207Z","end":"2026-07-09T16:45:45.638204Z","steps":["trace[1702802683] 'process raft request' (duration: 738.91047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:45.638321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:44.899194Z","time spent":"739.076884ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:46.229149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.638751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:46.22939Z","caller":"traceutil/trace.go:171","msg":"trace[356057996] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"1.014423675s","start":"2026-07-09T16:45:45.214944Z","end":"2026-07-09T16:45:46.229368Z","steps":["trace[356057996] 'process raft request' (duration: 871.483766ms)","trace[356057996] 'compare' (duration: 142.510209ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:46.229502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:45.21492Z","time spent":"1.014521837s","remote":"127.0.0.1:33396","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-07-09T16:45:46.229685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.269087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:45:46.229823Z","caller":"traceutil/trace.go:171","msg":"trace[1425369199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1365; }","duration":"935.40876ms","start":"2026-07-09T16:45:45.294404Z","end":"2026-07-09T16:45:46.229813Z","steps":["trace[1425369199] 'agreement among raft nodes before linearized reading' (duration: 935.197345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.229875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:45.294309Z","time spent":"935.557373ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T16:45:46.230214Z","caller":"traceutil/trace.go:171","msg":"trace[1080489160] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"934.932138ms","start":"2026-07-09T16:45:45.294412Z","end":"2026-07-09T16:45:46.229344Z","steps":["trace[1080489160] 'read index received' (duration: 344.139898ms)","trace[1080489160] 'applied index is now lower than readState.Index' (duration: 590.737419ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:46.230529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.582331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T16:45:46.23059Z","caller":"traceutil/trace.go:171","msg":"trace[411467269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1365; }","duration":"833.687114ms","start":"2026-07-09T16:45:45.396875Z","end":"2026-07-09T16:45:46.230562Z","steps":["trace[411467269] 'agreement among raft nodes before linearized reading' (duration: 833.50698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.230667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:45.396856Z","time spent":"833.794076ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:46.231173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.656486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-09T16:45:46.231239Z","caller":"traceutil/trace.go:171","msg":"trace[1455277300] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1365; }","duration":"206.759048ms","start":"2026-07-09T16:45:46.024471Z","end":"2026-07-09T16:45:46.23123Z","steps":["trace[1455277300] 'agreement among raft nodes before linearized reading' (duration: 206.637226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.231168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.806199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-07-09T16:45:46.231267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.534139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T16:45:46.231361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.420213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T16:45:46.231455Z","caller":"traceutil/trace.go:171","msg":"trace[1589362986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1365; }","duration":"833.712044ms","start":"2026-07-09T16:45:45.397699Z","end":"2026-07-09T16:45:46.231411Z","steps":["trace[1589362986] 'agreement among raft nodes before linearized reading' (duration: 833.512809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.231235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.950265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:45:46.231517Z","caller":"traceutil/trace.go:171","msg":"trace[974835650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1365; }","duration":"833.239653ms","start":"2026-07-09T16:45:45.398262Z","end":"2026-07-09T16:45:46.231501Z","steps":["trace[974835650] 'agreement among raft nodes before linearized reading' (duration: 832.901135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.231502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:45.397681Z","time spent":"833.810795ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:45:46.231578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:45.398257Z","time spent":"833.310054ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T16:45:46.231299Z","caller":"traceutil/trace.go:171","msg":"trace[1985914453] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1365; }","duration":"825.957192ms","start":"2026-07-09T16:45:45.405329Z","end":"2026-07-09T16:45:46.231286Z","steps":["trace[1985914453] 'agreement among raft nodes before linearized reading' (duration: 825.685595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.231676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:45.405323Z","time spent":"826.3433ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-09T16:45:46.231179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.289792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:46.231745Z","caller":"traceutil/trace.go:171","msg":"trace[1484909597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1365; }","duration":"508.896997ms","start":"2026-07-09T16:45:45.722839Z","end":"2026-07-09T16:45:46.231736Z","steps":["trace[1484909597] 'agreement among raft nodes before linearized reading' (duration: 508.276782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.231773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:45.722813Z","time spent":"508.953529ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:45:46.231501Z","caller":"traceutil/trace.go:171","msg":"trace[1267455457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1365; }","duration":"829.554036ms","start":"2026-07-09T16:45:45.401927Z","end":"2026-07-09T16:45:46.231481Z","steps":["trace[1267455457] 'agreement among raft nodes before linearized reading' (duration: 829.30712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.232016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:45.401905Z","time spent":"829.968567ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T16:45:52.462102Z","caller":"traceutil/trace.go:171","msg":"trace[1396344112] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"138.841985ms","start":"2026-07-09T16:45:52.323236Z","end":"2026-07-09T16:45:52.462078Z","steps":["trace[1396344112] 'process raft request' (duration: 69.671168ms)","trace[1396344112] 'compare' (duration: 69.063823ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:52.464373Z","caller":"traceutil/trace.go:171","msg":"trace[99271207] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"138.725552ms","start":"2026-07-09T16:45:52.325637Z","end":"2026-07-09T16:45:52.464362Z","steps":["trace[99271207] 'process raft request' (duration: 138.625809ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:52.464449Z","caller":"traceutil/trace.go:171","msg":"trace[747958283] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"140.718018ms","start":"2026-07-09T16:45:52.323715Z","end":"2026-07-09T16:45:52.464433Z","steps":["trace[747958283] 'process raft request' (duration: 140.398701ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:52.464502Z","caller":"traceutil/trace.go:171","msg":"trace[2098909906] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"139.88972ms","start":"2026-07-09T16:45:52.324605Z","end":"2026-07-09T16:45:52.464495Z","steps":["trace[2098909906] 'process raft request' (duration: 139.588913ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:52.464597Z","caller":"traceutil/trace.go:171","msg":"trace[2010017798] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"139.545531ms","start":"2026-07-09T16:45:52.325045Z","end":"2026-07-09T16:45:52.464591Z","steps":["trace[2010017798] 'process raft request' (duration: 139.181892ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:52.464377Z","caller":"traceutil/trace.go:171","msg":"trace[812665844] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"137.691177ms","start":"2026-07-09T16:45:52.326664Z","end":"2026-07-09T16:45:52.464355Z","steps":["trace[812665844] 'process raft request' (duration: 137.630416ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:08.765681Z","caller":"traceutil/trace.go:171","msg":"trace[484058297] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"101.460216ms","start":"2026-07-09T16:46:08.664124Z","end":"2026-07-09T16:46:08.765584Z","steps":["trace[484058297] 'process raft request' (duration: 101.147168ms)"],"step_count":1}