{"level":"warn","ts":"2026-05-08T10:24:39.64975Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T10:24:39.64983Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.54:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.54:2380","--initial-cluster=instance=https://199.19.213.54:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.54:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.54: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-05-08T10:24:39.649887Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T10:24:39.649897Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.54:2380"]} {"level":"info","ts":"2026-05-08T10:24:39.649914Z","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-05-08T10:24:39.650484Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"]} {"level":"info","ts":"2026-05-08T10:24:39.650568Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.54:2380"],"listen-peer-urls":["https://199.19.213.54:2380"],"advertise-client-urls":["https://199.19.213.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.54: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-05-08T10:24:39.655169Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.397833ms"} {"level":"info","ts":"2026-05-08T10:24:39.663084Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"193b4dda7792855b","cluster-id":"dd6702a2f79ce697"} {"level":"info","ts":"2026-05-08T10:24:39.663134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T10:24:39.663151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became follower at term 0"} {"level":"info","ts":"2026-05-08T10:24:39.66316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 193b4dda7792855b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T10:24:39.663166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became follower at term 1"} {"level":"info","ts":"2026-05-08T10:24:39.663193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=(1818132475278427483)"} {"level":"warn","ts":"2026-05-08T10:24:39.670207Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T10:24:39.671913Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T10:24:39.67381Z","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-05-08T10:24:39.675974Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"193b4dda7792855b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T10:24:39.676125Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"193b4dda7792855b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T10:24:39.676356Z","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-05-08T10:24:39.67653Z","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-05-08T10:24:39.67655Z","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-05-08T10:24:39.677498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=(1818132475278427483)"} {"level":"info","ts":"2026-05-08T10:24:39.677787Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dd6702a2f79ce697","local-member-id":"193b4dda7792855b","added-peer-id":"193b4dda7792855b","added-peer-peer-urls":["https://199.19.213.54:2380"]} {"level":"info","ts":"2026-05-08T10:24:39.681445Z","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-05-08T10:24:39.68175Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"193b4dda7792855b","initial-advertise-peer-urls":["https://199.19.213.54:2380"],"listen-peer-urls":["https://199.19.213.54:2380"],"advertise-client-urls":["https://199.19.213.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T10:24:39.681541Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.54:2380"} {"level":"info","ts":"2026-05-08T10:24:39.682382Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.54:2380"} {"level":"info","ts":"2026-05-08T10:24:39.681856Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T10:24:39.863324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T10:24:39.863392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T10:24:39.863441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b received MsgPreVoteResp from 193b4dda7792855b at term 1"} {"level":"info","ts":"2026-05-08T10:24:39.863479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became candidate at term 2"} {"level":"info","ts":"2026-05-08T10:24:39.8635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b received MsgVoteResp from 193b4dda7792855b at term 2"} {"level":"info","ts":"2026-05-08T10:24:39.863522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became leader at term 2"} {"level":"info","ts":"2026-05-08T10:24:39.863545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 193b4dda7792855b elected leader 193b4dda7792855b at term 2"} {"level":"info","ts":"2026-05-08T10:24:39.864663Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:24:39.865448Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"193b4dda7792855b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.54:2379]}","request-path":"/0/members/193b4dda7792855b/attributes","cluster-id":"dd6702a2f79ce697","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T10:24:39.865546Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T10:24:39.86591Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dd6702a2f79ce697","local-member-id":"193b4dda7792855b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:24:39.86603Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:24:39.86607Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:24:39.866273Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T10:24:39.866446Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T10:24:39.866464Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T10:24:39.873133Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.54:2379"} {"level":"info","ts":"2026-05-08T10:24:39.873217Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-08T10:25:08.808758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.867759ms","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-05-08T10:25:08.808817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.711409ms","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-05-08T10:25:08.808907Z","caller":"traceutil/trace.go:171","msg":"trace[198318901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"117.879562ms","start":"2026-05-08T10:25:08.691007Z","end":"2026-05-08T10:25:08.808887Z","steps":["trace[198318901] 'range keys from in-memory index tree' (duration: 117.527696ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:08.808887Z","caller":"traceutil/trace.go:171","msg":"trace[95674448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:502; }","duration":"149.081153ms","start":"2026-05-08T10:25:08.659786Z","end":"2026-05-08T10:25:08.808867Z","steps":["trace[95674448] 'range keys from in-memory index tree' (duration: 148.618154ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:09.468801Z","caller":"traceutil/trace.go:171","msg":"trace[1436856295] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"261.33151ms","start":"2026-05-08T10:25:09.207418Z","end":"2026-05-08T10:25:09.46875Z","steps":["trace[1436856295] 'process raft request' (duration: 254.174214ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:09.469605Z","caller":"traceutil/trace.go:171","msg":"trace[607854503] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"260.605017ms","start":"2026-05-08T10:25:09.208225Z","end":"2026-05-08T10:25:09.46883Z","steps":["trace[607854503] 'process raft request' (duration: 260.385773ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:10.773705Z","caller":"traceutil/trace.go:171","msg":"trace[1237221333] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"451.324929ms","start":"2026-05-08T10:25:10.322359Z","end":"2026-05-08T10:25:10.773684Z","steps":["trace[1237221333] 'process raft request' (duration: 449.742949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:10.774228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:10.322331Z","time spent":"451.44168ms","remote":"127.0.0.1:51416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:25:10.778296Z","caller":"traceutil/trace.go:171","msg":"trace[1223467120] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"410.469985ms","start":"2026-05-08T10:25:10.367815Z","end":"2026-05-08T10:25:10.778285Z","steps":["trace[1223467120] 'process raft request' (duration: 410.389394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:10.778349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:10.367781Z","time spent":"410.545857ms","remote":"127.0.0.1:51416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:25:11.508223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609447983333569446,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T10:25:11.607062Z","caller":"traceutil/trace.go:171","msg":"trace[593916759] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"602.674895ms","start":"2026-05-08T10:25:11.004322Z","end":"2026-05-08T10:25:11.606997Z","steps":["trace[593916759] 'process raft request' (duration: 600.003446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:11.607304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:11.004308Z","time spent":"602.858141ms","remote":"127.0.0.1:51416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:25:11.639617Z","caller":"traceutil/trace.go:171","msg":"trace[995233829] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"632.350178ms","start":"2026-05-08T10:25:11.007225Z","end":"2026-05-08T10:25:11.639575Z","steps":["trace[995233829] 'read index received' (duration: 596.474728ms)","trace[995233829] 'applied index is now lower than readState.Index' (duration: 35.87466ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:11.639702Z","caller":"traceutil/trace.go:171","msg":"trace[248909189] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"578.680491ms","start":"2026-05-08T10:25:11.061014Z","end":"2026-05-08T10:25:11.639695Z","steps":["trace[248909189] 'process raft request' (duration: 578.397916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:11.639786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:11.060979Z","time spent":"578.739812ms","remote":"127.0.0.1:51416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:25:11.639954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.728635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"info","ts":"2026-05-08T10:25:11.639974Z","caller":"traceutil/trace.go:171","msg":"trace[772394523] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:583; }","duration":"632.749345ms","start":"2026-05-08T10:25:11.007219Z","end":"2026-05-08T10:25:11.639968Z","steps":["trace[772394523] 'agreement among raft nodes before linearized reading' (duration: 632.661304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:11.639989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:11.007162Z","time spent":"632.824018ms","remote":"127.0.0.1:51416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9172,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" "} {"level":"warn","ts":"2026-05-08T10:25:11.640047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.619634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:11.640057Z","caller":"traceutil/trace.go:171","msg":"trace[1101829479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"622.645515ms","start":"2026-05-08T10:25:11.017408Z","end":"2026-05-08T10:25:11.640054Z","steps":["trace[1101829479] 'agreement among raft nodes before linearized reading' (duration: 622.627905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:11.640068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:11.017401Z","time spent":"622.664505ms","remote":"127.0.0.1:51370","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-05-08T10:25:13.99064Z","caller":"traceutil/trace.go:171","msg":"trace[1317946395] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"124.378127ms","start":"2026-05-08T10:25:13.866233Z","end":"2026-05-08T10:25:13.990612Z","steps":["trace[1317946395] 'process raft request' (duration: 118.129148ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:16.98142Z","caller":"traceutil/trace.go:171","msg":"trace[501180219] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"201.994776ms","start":"2026-05-08T10:25:16.779395Z","end":"2026-05-08T10:25:16.98139Z","steps":["trace[501180219] 'process raft request' (duration: 200.856584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:17.132073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.913739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:25:17.132399Z","caller":"traceutil/trace.go:171","msg":"trace[1520366274] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"143.03213ms","start":"2026-05-08T10:25:16.989347Z","end":"2026-05-08T10:25:17.132379Z","steps":["trace[1520366274] 'process raft request' (duration: 142.880647ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:17.132492Z","caller":"traceutil/trace.go:171","msg":"trace[1419215303] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"145.618518ms","start":"2026-05-08T10:25:16.986798Z","end":"2026-05-08T10:25:17.132416Z","steps":["trace[1419215303] 'process raft request' (duration: 42.000495ms)","trace[1419215303] 'compare' (duration: 102.801467ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:25:17.760304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.641556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:25:17.760972Z","caller":"traceutil/trace.go:171","msg":"trace[910475173] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"705.117495ms","start":"2026-05-08T10:25:17.055804Z","end":"2026-05-08T10:25:17.760921Z","steps":["trace[910475173] 'process raft request' (duration: 414.766115ms)","trace[910475173] 'compare' (duration: 289.370721ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:25:17.761146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.705022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mssgx\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-08T10:25:17.76065Z","caller":"traceutil/trace.go:171","msg":"trace[1788011912] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"388.185452ms","start":"2026-05-08T10:25:17.372425Z","end":"2026-05-08T10:25:17.76061Z","steps":["trace[1788011912] 'read index received' (duration: 98.042247ms)","trace[1788011912] 'applied index is now lower than readState.Index' (duration: 290.141445ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:17.761265Z","caller":"traceutil/trace.go:171","msg":"trace[1911748178] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mssgx; range_end:; response_count:1; response_revision:621; }","duration":"388.859956ms","start":"2026-05-08T10:25:17.372374Z","end":"2026-05-08T10:25:17.761234Z","steps":["trace[1911748178] 'agreement among raft nodes before linearized reading' (duration: 388.620541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:17.761341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:17.372348Z","time spent":"388.977827ms","remote":"127.0.0.1:51532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-mssgx\" "} {"level":"warn","ts":"2026-05-08T10:25:17.761393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.29964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:17.761462Z","caller":"traceutil/trace.go:171","msg":"trace[1893787305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"165.404472ms","start":"2026-05-08T10:25:17.596031Z","end":"2026-05-08T10:25:17.761435Z","steps":["trace[1893787305] 'agreement among raft nodes before linearized reading' (duration: 165.30014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:17.76127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:17.05578Z","time spent":"705.283778ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:25:18.520174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609447983333569547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T10:25:19.02059Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609447983333569547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T10:25:19.252744Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.475841205s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T10:25:19.253186Z","caller":"traceutil/trace.go:171","msg":"trace[373131770] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.47661473s","start":"2026-05-08T10:25:17.776545Z","end":"2026-05-08T10:25:19.25316Z","steps":["trace[373131770] 'process raft request' (duration: 1.476446306s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:19.253348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:17.77653Z","time spent":"1.476739242s","remote":"127.0.0.1:51532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T10:25:19.283775Z","caller":"traceutil/trace.go:171","msg":"trace[905694517] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.506649158s","start":"2026-05-08T10:25:17.77709Z","end":"2026-05-08T10:25:19.283739Z","steps":["trace[905694517] 'process raft request' (duration: 1.506483275s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:19.283949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:17.777073Z","time spent":"1.506781131s","remote":"127.0.0.1:51532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:25:19.292397Z","caller":"traceutil/trace.go:171","msg":"trace[1937617263] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:643; }","duration":"1.272417862s","start":"2026-05-08T10:25:18.019958Z","end":"2026-05-08T10:25:19.292376Z","steps":["trace[1937617263] 'read index received' (duration: 1.233092006s)","trace[1937617263] 'applied index is now lower than readState.Index' (duration: 39.324266ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:25:19.292559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272586584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:19.292596Z","caller":"traceutil/trace.go:171","msg":"trace[389324079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"1.272670906s","start":"2026-05-08T10:25:18.019915Z","end":"2026-05-08T10:25:19.292586Z","steps":["trace[389324079] 'agreement among raft nodes before linearized reading' (duration: 1.272575505s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:19.292624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:18.019892Z","time spent":"1.272726017s","remote":"127.0.0.1:51370","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-05-08T10:25:19.295622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201172943s","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-05-08T10:25:19.295659Z","caller":"traceutil/trace.go:171","msg":"trace[2109825789] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"1.201255153s","start":"2026-05-08T10:25:18.094395Z","end":"2026-05-08T10:25:19.295651Z","steps":["trace[2109825789] 'agreement among raft nodes before linearized reading' (duration: 1.201101741s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:19.295678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:18.094366Z","time spent":"1.201307325s","remote":"127.0.0.1:51622","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-05-08T10:25:19.295899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.978151ms","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-05-08T10:25:19.295943Z","caller":"traceutil/trace.go:171","msg":"trace[611942840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"160.071832ms","start":"2026-05-08T10:25:19.135861Z","end":"2026-05-08T10:25:19.295933Z","steps":["trace[611942840] 'agreement among raft nodes before linearized reading' (duration: 159.95088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:19.29597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177422692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T10:25:19.295998Z","caller":"traceutil/trace.go:171","msg":"trace[631624876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"1.177486304s","start":"2026-05-08T10:25:18.118503Z","end":"2026-05-08T10:25:19.295989Z","steps":["trace[631624876] 'agreement among raft nodes before linearized reading' (duration: 1.177404892s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:19.296022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:18.118472Z","time spent":"1.177543494s","remote":"127.0.0.1:51622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T10:25:19.296112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.130594ms","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-05-08T10:25:19.296142Z","caller":"traceutil/trace.go:171","msg":"trace[1166930246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"160.213645ms","start":"2026-05-08T10:25:19.13592Z","end":"2026-05-08T10:25:19.296133Z","steps":["trace[1166930246] 'agreement among raft nodes before linearized reading' (duration: 160.134754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:20.828799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.70912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:20.828883Z","caller":"traceutil/trace.go:171","msg":"trace[1115217496] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:642; }","duration":"137.829041ms","start":"2026-05-08T10:25:20.69103Z","end":"2026-05-08T10:25:20.828859Z","steps":["trace[1115217496] 'count revisions from in-memory index tree' (duration: 137.623156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:20.829063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.970043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:20.829089Z","caller":"traceutil/trace.go:171","msg":"trace[1602805666] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:642; }","duration":"214.031584ms","start":"2026-05-08T10:25:20.615051Z","end":"2026-05-08T10:25:20.829082Z","steps":["trace[1602805666] 'range keys from in-memory index tree' (duration: 213.915902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:20.829216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.77706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:25:20.829101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.592753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:20.829279Z","caller":"traceutil/trace.go:171","msg":"trace[1673960827] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:642; }","duration":"112.802377ms","start":"2026-05-08T10:25:20.716456Z","end":"2026-05-08T10:25:20.829259Z","steps":["trace[1673960827] 'count revisions from in-memory index tree' (duration: 112.503031ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:20.82924Z","caller":"traceutil/trace.go:171","msg":"trace[569705264] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:642; }","duration":"213.858051ms","start":"2026-05-08T10:25:20.615377Z","end":"2026-05-08T10:25:20.829235Z","steps":["trace[569705264] 'count revisions from in-memory index tree' (duration: 213.638016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:20.829545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.474554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:20.82958Z","caller":"traceutil/trace.go:171","msg":"trace[543594775] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:642; }","duration":"138.528304ms","start":"2026-05-08T10:25:20.691041Z","end":"2026-05-08T10:25:20.82957Z","steps":["trace[543594775] 'range keys from in-memory index tree' (duration: 138.40622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:20.829559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.01065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:25:20.829662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.558119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:20.829656Z","caller":"traceutil/trace.go:171","msg":"trace[1242034019] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:642; }","duration":"113.149042ms","start":"2026-05-08T10:25:20.716475Z","end":"2026-05-08T10:25:20.829624Z","steps":["trace[1242034019] 'range keys from in-memory index tree' (duration: 112.926089ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:20.829707Z","caller":"traceutil/trace.go:171","msg":"trace[365339856] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:642; }","duration":"161.676371ms","start":"2026-05-08T10:25:20.668015Z","end":"2026-05-08T10:25:20.829691Z","steps":["trace[365339856] 'count revisions from in-memory index tree' (duration: 161.481468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:20.829574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.641733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:25:20.829663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.03422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:20.829844Z","caller":"traceutil/trace.go:171","msg":"trace[1742745869] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:642; }","duration":"112.977181ms","start":"2026-05-08T10:25:20.716855Z","end":"2026-05-08T10:25:20.829832Z","steps":["trace[1742745869] 'range keys from in-memory index tree' (duration: 112.594663ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:20.829865Z","caller":"traceutil/trace.go:171","msg":"trace[122750555] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:642; }","duration":"161.233544ms","start":"2026-05-08T10:25:20.668622Z","end":"2026-05-08T10:25:20.829856Z","steps":["trace[122750555] 'range keys from in-memory index tree' (duration: 160.955508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:20.829816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.742204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:20.830026Z","caller":"traceutil/trace.go:171","msg":"trace[300463188] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:642; }","duration":"162.040989ms","start":"2026-05-08T10:25:20.667961Z","end":"2026-05-08T10:25:20.830002Z","steps":["trace[300463188] 'range keys from in-memory index tree' (duration: 161.606341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:20.830118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.010984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:20.830177Z","caller":"traceutil/trace.go:171","msg":"trace[1803331182] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:642; }","duration":"139.099656ms","start":"2026-05-08T10:25:20.691063Z","end":"2026-05-08T10:25:20.830163Z","steps":["trace[1803331182] 'range keys from in-memory index tree' (duration: 138.959662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:20.830264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.109337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:20.830303Z","caller":"traceutil/trace.go:171","msg":"trace[145608684] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:642; }","duration":"214.167256ms","start":"2026-05-08T10:25:20.61612Z","end":"2026-05-08T10:25:20.830287Z","steps":["trace[145608684] 'range keys from in-memory index tree' (duration: 213.044005ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:34.84156Z","caller":"traceutil/trace.go:171","msg":"trace[812577876] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"353.033597ms","start":"2026-05-08T10:25:34.488497Z","end":"2026-05-08T10:25:34.84153Z","steps":["trace[812577876] 'process raft request' (duration: 352.826344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:34.841764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:34.488467Z","time spent":"353.19956ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:25:43.110409Z","caller":"traceutil/trace.go:171","msg":"trace[167180783] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"130.040073ms","start":"2026-05-08T10:25:42.979753Z","end":"2026-05-08T10:25:43.109793Z","steps":["trace[167180783] 'process raft request' (duration: 129.820309ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:43.50893Z","caller":"traceutil/trace.go:171","msg":"trace[611214678] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"105.810584ms","start":"2026-05-08T10:25:43.403081Z","end":"2026-05-08T10:25:43.508892Z","steps":["trace[611214678] 'process raft request' (duration: 105.554549ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:04.937787Z","caller":"traceutil/trace.go:171","msg":"trace[1770658252] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"820.857616ms","start":"2026-05-08T10:26:04.116904Z","end":"2026-05-08T10:26:04.937762Z","steps":["trace[1770658252] 'process raft request' (duration: 820.674513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:04.93798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:04.11688Z","time spent":"820.991108ms","remote":"127.0.0.1:51622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:26:12.269787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.038595ms","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-05-08T10:26:12.26987Z","caller":"traceutil/trace.go:171","msg":"trace[216484296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"427.178098ms","start":"2026-05-08T10:26:11.842674Z","end":"2026-05-08T10:26:12.269852Z","steps":["trace[216484296] 'range keys from in-memory index tree' (duration: 426.862962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:12.269918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:11.842654Z","time spent":"427.253989ms","remote":"127.0.0.1:51622","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-05-08T10:26:12.270264Z","caller":"traceutil/trace.go:171","msg":"trace[1993587545] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"387.818257ms","start":"2026-05-08T10:26:11.882425Z","end":"2026-05-08T10:26:12.270243Z","steps":["trace[1993587545] 'read index received' (duration: 386.627055ms)","trace[1993587545] 'applied index is now lower than readState.Index' (duration: 1.189952ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:12.270336Z","caller":"traceutil/trace.go:171","msg":"trace[2012462202] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"417.696439ms","start":"2026-05-08T10:26:11.852608Z","end":"2026-05-08T10:26:12.270305Z","steps":["trace[2012462202] 'process raft request' (duration: 416.518147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:12.270448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.332446ms","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-05-08T10:26:12.270474Z","caller":"traceutil/trace.go:171","msg":"trace[1865712155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1003; }","duration":"347.399716ms","start":"2026-05-08T10:26:11.923067Z","end":"2026-05-08T10:26:12.270467Z","steps":["trace[1865712155] 'agreement among raft nodes before linearized reading' (duration: 347.284514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:12.270459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.798766ms","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-05-08T10:26:12.270483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:11.85258Z","time spent":"417.823831ms","remote":"127.0.0.1:51622","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-05-08T10:26:12.270499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:11.923053Z","time spent":"347.440548ms","remote":"127.0.0.1:51622","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-05-08T10:26:12.27051Z","caller":"traceutil/trace.go:171","msg":"trace[875793983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"328.884068ms","start":"2026-05-08T10:26:11.941612Z","end":"2026-05-08T10:26:12.270496Z","steps":["trace[875793983] 'agreement among raft nodes before linearized reading' (duration: 328.754595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:12.270545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:11.941595Z","time spent":"328.943409ms","remote":"127.0.0.1:51622","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-05-08T10:26:12.270498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.066112ms","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-05-08T10:26:12.270609Z","caller":"traceutil/trace.go:171","msg":"trace[1461721228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1003; }","duration":"388.235395ms","start":"2026-05-08T10:26:11.882351Z","end":"2026-05-08T10:26:12.270586Z","steps":["trace[1461721228] 'agreement among raft nodes before linearized reading' (duration: 387.979011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:12.270655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:11.882323Z","time spent":"388.321737ms","remote":"127.0.0.1:51622","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-05-08T10:26:18.448103Z","caller":"traceutil/trace.go:171","msg":"trace[1641071925] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"134.318767ms","start":"2026-05-08T10:26:18.313759Z","end":"2026-05-08T10:26:18.448078Z","steps":["trace[1641071925] 'process raft request' (duration: 134.141513ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:18.448398Z","caller":"traceutil/trace.go:171","msg":"trace[1153019570] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"133.004652ms","start":"2026-05-08T10:26:18.315371Z","end":"2026-05-08T10:26:18.448375Z","steps":["trace[1153019570] 'process raft request' (duration: 132.669016ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:18.448398Z","caller":"traceutil/trace.go:171","msg":"trace[732707131] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"132.040636ms","start":"2026-05-08T10:26:18.316339Z","end":"2026-05-08T10:26:18.44838Z","steps":["trace[732707131] 'process raft request' (duration: 131.952194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:33.963105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609447983333570571,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T10:26:34.463811Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609447983333570571,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T10:26:34.621151Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.221088426s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-08T10:26:34.62153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:33.399788Z","time spent":"1.221737869s","remote":"127.0.0.1:51402","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-08T10:26:34.652707Z","caller":"traceutil/trace.go:171","msg":"trace[1643234048] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1115; }","duration":"1.189782138s","start":"2026-05-08T10:26:33.462833Z","end":"2026-05-08T10:26:34.652615Z","steps":["trace[1643234048] 'read index received' (duration: 1.158668853s)","trace[1643234048] 'applied index is now lower than readState.Index' (duration: 31.111975ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:34.652785Z","caller":"traceutil/trace.go:171","msg":"trace[863588135] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"298.556878ms","start":"2026-05-08T10:26:34.354193Z","end":"2026-05-08T10:26:34.65275Z","steps":["trace[863588135] 'process raft request' (duration: 277.999112ms)","trace[863588135] 'compare' (duration: 20.167858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:34.653244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19040099s","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-05-08T10:26:34.653319Z","caller":"traceutil/trace.go:171","msg":"trace[1229331588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1074; }","duration":"1.190526423s","start":"2026-05-08T10:26:33.46278Z","end":"2026-05-08T10:26:34.653307Z","steps":["trace[1229331588] 'agreement among raft nodes before linearized reading' (duration: 1.190030463s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:34.653565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:33.462755Z","time spent":"1.190694515s","remote":"127.0.0.1:51622","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-05-08T10:26:34.656048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187360982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:26:34.656106Z","caller":"traceutil/trace.go:171","msg":"trace[1437918639] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1075; }","duration":"1.187439823s","start":"2026-05-08T10:26:33.468651Z","end":"2026-05-08T10:26:34.65609Z","steps":["trace[1437918639] 'agreement among raft nodes before linearized reading' (duration: 1.187244449s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:34.656145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:33.46864Z","time spent":"1.187495355s","remote":"127.0.0.1:39568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:26:34.656235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.389075ms","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-05-08T10:26:34.656284Z","caller":"traceutil/trace.go:171","msg":"trace[795124282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1075; }","duration":"230.484257ms","start":"2026-05-08T10:26:34.425789Z","end":"2026-05-08T10:26:34.656273Z","steps":["trace[795124282] 'agreement among raft nodes before linearized reading' (duration: 230.397136ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:34.800092Z","caller":"traceutil/trace.go:171","msg":"trace[235309438] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"139.658698ms","start":"2026-05-08T10:26:34.660415Z","end":"2026-05-08T10:26:34.800074Z","steps":["trace[235309438] 'process raft request' (duration: 139.616017ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:34.800134Z","caller":"traceutil/trace.go:171","msg":"trace[737319009] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"139.79915ms","start":"2026-05-08T10:26:34.660299Z","end":"2026-05-08T10:26:34.800098Z","steps":["trace[737319009] 'process raft request' (duration: 139.589606ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:34.800253Z","caller":"traceutil/trace.go:171","msg":"trace[1285961536] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1117; }","duration":"139.812091ms","start":"2026-05-08T10:26:34.660433Z","end":"2026-05-08T10:26:34.800245Z","steps":["trace[1285961536] 'read index received' (duration: 54.918923ms)","trace[1285961536] 'applied index is now lower than readState.Index' (duration: 84.892368ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:34.80033Z","caller":"traceutil/trace.go:171","msg":"trace[751668278] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"139.309932ms","start":"2026-05-08T10:26:34.661004Z","end":"2026-05-08T10:26:34.800313Z","steps":["trace[751668278] 'process raft request' (duration: 139.045796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:34.800373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.926113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-08T10:26:34.800392Z","caller":"traceutil/trace.go:171","msg":"trace[955908503] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1080; }","duration":"139.971694ms","start":"2026-05-08T10:26:34.660415Z","end":"2026-05-08T10:26:34.800387Z","steps":["trace[955908503] 'agreement among raft nodes before linearized reading' (duration: 139.855762ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:34.800172Z","caller":"traceutil/trace.go:171","msg":"trace[2074640004] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"139.77297ms","start":"2026-05-08T10:26:34.660378Z","end":"2026-05-08T10:26:34.800151Z","steps":["trace[2074640004] 'process raft request' (duration: 139.631536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:36.411871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.36442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:26:36.411993Z","caller":"traceutil/trace.go:171","msg":"trace[1113001033] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1081; }","duration":"345.553132ms","start":"2026-05-08T10:26:36.066412Z","end":"2026-05-08T10:26:36.411965Z","steps":["trace[1113001033] 'count revisions from in-memory index tree' (duration: 345.264618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:36.412082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:36.066391Z","time spent":"345.671075ms","remote":"127.0.0.1:39590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-05-08T10:26:36.936494Z","caller":"traceutil/trace.go:171","msg":"trace[1869502163] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"127.118141ms","start":"2026-05-08T10:26:36.809355Z","end":"2026-05-08T10:26:36.936474Z","steps":["trace[1869502163] 'process raft request' (duration: 127.084501ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:36.936725Z","caller":"traceutil/trace.go:171","msg":"trace[728665685] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"127.939888ms","start":"2026-05-08T10:26:36.808774Z","end":"2026-05-08T10:26:36.936714Z","steps":["trace[728665685] 'process raft request' (duration: 127.631392ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:36.93671Z","caller":"traceutil/trace.go:171","msg":"trace[122680103] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"128.363044ms","start":"2026-05-08T10:26:36.808286Z","end":"2026-05-08T10:26:36.936649Z","steps":["trace[122680103] 'process raft request' (duration: 128.054679ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:38.968787Z","caller":"traceutil/trace.go:171","msg":"trace[1705027525] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"185.125653ms","start":"2026-05-08T10:26:38.783631Z","end":"2026-05-08T10:26:38.968757Z","steps":["trace[1705027525] 'read index received' (duration: 184.919499ms)","trace[1705027525] 'applied index is now lower than readState.Index' (duration: 204.634µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:38.968916Z","caller":"traceutil/trace.go:171","msg":"trace[362322640] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"243.276328ms","start":"2026-05-08T10:26:38.725625Z","end":"2026-05-08T10:26:38.968902Z","steps":["trace[362322640] 'process raft request' (duration: 242.922401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:38.969108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.462349ms","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-05-08T10:26:38.969205Z","caller":"traceutil/trace.go:171","msg":"trace[152897240] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1089; }","duration":"185.605982ms","start":"2026-05-08T10:26:38.783577Z","end":"2026-05-08T10:26:38.969183Z","steps":["trace[152897240] 'agreement among raft nodes before linearized reading' (duration: 185.396608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:39.150529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.490022ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-08T10:26:39.150773Z","caller":"traceutil/trace.go:171","msg":"trace[472177487] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"176.319078ms","start":"2026-05-08T10:26:38.974437Z","end":"2026-05-08T10:26:39.150756Z","steps":["trace[472177487] 'process raft request' (duration: 176.191136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:39.15092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.480261ms","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-05-08T10:26:39.150963Z","caller":"traceutil/trace.go:171","msg":"trace[1031620599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"269.570463ms","start":"2026-05-08T10:26:38.881382Z","end":"2026-05-08T10:26:39.150953Z","steps":["trace[1031620599] 'agreement among raft nodes before linearized reading' (duration: 269.42692ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:39.150775Z","caller":"traceutil/trace.go:171","msg":"trace[955700239] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"181.867033ms","start":"2026-05-08T10:26:38.968863Z","end":"2026-05-08T10:26:39.15073Z","steps":["trace[955700239] 'read index received' (duration: 40.172086ms)","trace[955700239] 'applied index is now lower than readState.Index' (duration: 141.689707ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:39.151176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.043793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:26:39.151212Z","caller":"traceutil/trace.go:171","msg":"trace[2132526514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"133.118915ms","start":"2026-05-08T10:26:39.018083Z","end":"2026-05-08T10:26:39.151202Z","steps":["trace[2132526514] 'agreement among raft nodes before linearized reading' (duration: 133.056804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:39.151239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.996662ms","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-05-08T10:26:39.15131Z","caller":"traceutil/trace.go:171","msg":"trace[1086890216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"210.075023ms","start":"2026-05-08T10:26:38.941194Z","end":"2026-05-08T10:26:39.151269Z","steps":["trace[1086890216] 'agreement among raft nodes before linearized reading' (duration: 209.979272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:39.151493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.265236ms","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-05-08T10:26:39.151527Z","caller":"traceutil/trace.go:171","msg":"trace[1646105979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1090; }","duration":"210.325618ms","start":"2026-05-08T10:26:38.941194Z","end":"2026-05-08T10:26:39.15152Z","steps":["trace[1646105979] 'agreement among raft nodes before linearized reading' (duration: 210.229936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:39.151673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.494411ms","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-05-08T10:26:39.151712Z","caller":"traceutil/trace.go:171","msg":"trace[1699328892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"210.567822ms","start":"2026-05-08T10:26:38.941134Z","end":"2026-05-08T10:26:39.151702Z","steps":["trace[1699328892] 'agreement among raft nodes before linearized reading' (duration: 210.43ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:39.586569Z","caller":"traceutil/trace.go:171","msg":"trace[433009067] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"430.843957ms","start":"2026-05-08T10:26:39.155698Z","end":"2026-05-08T10:26:39.586542Z","steps":["trace[433009067] 'process raft request' (duration: 430.669183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:39.586722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:39.15567Z","time spent":"430.969069ms","remote":"127.0.0.1:51622","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-05-08T10:26:39.663141Z","caller":"traceutil/trace.go:171","msg":"trace[675265984] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"507.320567ms","start":"2026-05-08T10:26:39.155799Z","end":"2026-05-08T10:26:39.66312Z","steps":["trace[675265984] 'process raft request' (duration: 451.060708ms)","trace[675265984] 'compare' (duration: 56.044505ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:39.663164Z","caller":"traceutil/trace.go:171","msg":"trace[1007073868] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"506.319337ms","start":"2026-05-08T10:26:39.156819Z","end":"2026-05-08T10:26:39.663138Z","steps":["trace[1007073868] 'process raft request' (duration: 506.266146ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:39.663225Z","caller":"traceutil/trace.go:171","msg":"trace[1122229036] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"506.627962ms","start":"2026-05-08T10:26:39.15658Z","end":"2026-05-08T10:26:39.663208Z","steps":["trace[1122229036] 'process raft request' (duration: 506.455399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:39.663319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:39.156569Z","time spent":"506.695504ms","remote":"127.0.0.1:51622","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-05-08T10:26:39.663361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:39.156811Z","time spent":"506.438739ms","remote":"127.0.0.1:51622","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-05-08T10:26:39.663452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:39.155785Z","time spent":"507.445399ms","remote":"127.0.0.1:51622","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-05-08T10:26:47.938717Z","caller":"traceutil/trace.go:171","msg":"trace[1572746544] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"189.470755ms","start":"2026-05-08T10:26:47.749221Z","end":"2026-05-08T10:26:47.938692Z","steps":["trace[1572746544] 'process raft request' (duration: 189.277181ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:47.938811Z","caller":"traceutil/trace.go:171","msg":"trace[1168006540] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"189.365113ms","start":"2026-05-08T10:26:47.749421Z","end":"2026-05-08T10:26:47.938786Z","steps":["trace[1168006540] 'process raft request' (duration: 189.22182ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:47.946128Z","caller":"traceutil/trace.go:171","msg":"trace[2089800015] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"129.233853ms","start":"2026-05-08T10:26:47.81687Z","end":"2026-05-08T10:26:47.946104Z","steps":["trace[2089800015] 'process raft request' (duration: 129.110179ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:57.456204Z","caller":"traceutil/trace.go:171","msg":"trace[1409557443] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"206.869463ms","start":"2026-05-08T10:26:57.249316Z","end":"2026-05-08T10:26:57.456185Z","steps":["trace[1409557443] 'read index received' (duration: 206.73624ms)","trace[1409557443] 'applied index is now lower than readState.Index' (duration: 132.343µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:57.456375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.044896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T10:26:57.456398Z","caller":"traceutil/trace.go:171","msg":"trace[897361792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1209; }","duration":"207.146188ms","start":"2026-05-08T10:26:57.249245Z","end":"2026-05-08T10:26:57.456391Z","steps":["trace[897361792] 'agreement among raft nodes before linearized reading' (duration: 207.012125ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:57.456488Z","caller":"traceutil/trace.go:171","msg":"trace[709702896] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"425.960215ms","start":"2026-05-08T10:26:57.030507Z","end":"2026-05-08T10:26:57.456467Z","steps":["trace[709702896] 'process raft request' (duration: 425.566958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:57.456594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:57.030494Z","time spent":"426.045246ms","remote":"127.0.0.1:51622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:26:57.804981Z","caller":"traceutil/trace.go:171","msg":"trace[241024317] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"345.078633ms","start":"2026-05-08T10:26:57.459867Z","end":"2026-05-08T10:26:57.804945Z","steps":["trace[241024317] 'process raft request' (duration: 259.86174ms)","trace[241024317] 'compare' (duration: 84.807756ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:57.805119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:57.459851Z","time spent":"345.194766ms","remote":"127.0.0.1:51622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:26:58.125556Z","caller":"traceutil/trace.go:171","msg":"trace[666273017] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"299.422755ms","start":"2026-05-08T10:26:57.826105Z","end":"2026-05-08T10:26:58.125528Z","steps":["trace[666273017] 'read index received' (duration: 293.252589ms)","trace[666273017] 'applied index is now lower than readState.Index' (duration: 6.168656ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:58.125692Z","caller":"traceutil/trace.go:171","msg":"trace[237161318] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"314.360355ms","start":"2026-05-08T10:26:57.811319Z","end":"2026-05-08T10:26:58.125679Z","steps":["trace[237161318] 'process raft request' (duration: 308.101588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:58.125764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.66558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:26:58.125791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:57.811303Z","time spent":"314.415907ms","remote":"127.0.0.1:51622","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-05-08T10:26:58.125829Z","caller":"traceutil/trace.go:171","msg":"trace[1244169122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"103.768713ms","start":"2026-05-08T10:26:58.022044Z","end":"2026-05-08T10:26:58.125813Z","steps":["trace[1244169122] 'agreement among raft nodes before linearized reading' (duration: 103.666181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:58.12602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.908653ms","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-05-08T10:26:58.126081Z","caller":"traceutil/trace.go:171","msg":"trace[1202292909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"299.988724ms","start":"2026-05-08T10:26:57.826074Z","end":"2026-05-08T10:26:58.126063Z","steps":["trace[1202292909] 'agreement among raft nodes before linearized reading' (duration: 299.858542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:58.12612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:57.826065Z","time spent":"300.044765ms","remote":"127.0.0.1:51622","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-05-08T10:26:58.12631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.649703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T10:26:58.126346Z","caller":"traceutil/trace.go:171","msg":"trace[51808589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"100.684183ms","start":"2026-05-08T10:26:58.02565Z","end":"2026-05-08T10:26:58.126334Z","steps":["trace[51808589] 'agreement among raft nodes before linearized reading' (duration: 100.337647ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:08.421495Z","caller":"traceutil/trace.go:171","msg":"trace[7067747] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"101.256772ms","start":"2026-05-08T10:27:08.320222Z","end":"2026-05-08T10:27:08.421479Z","steps":["trace[7067747] 'process raft request' (duration: 58.018206ms)","trace[7067747] 'compare' (duration: 43.187895ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:27:09.025554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.616689ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-08T10:27:14.875875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.327427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:27:14.87603Z","caller":"traceutil/trace.go:171","msg":"trace[59981736] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"575.094708ms","start":"2026-05-08T10:27:14.300917Z","end":"2026-05-08T10:27:14.876012Z","steps":["trace[59981736] 'process raft request' (duration: 333.537677ms)","trace[59981736] 'compare' (duration: 240.899209ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:27:14.876112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:14.300907Z","time spent":"575.163249ms","remote":"127.0.0.1:51622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:27:14.876265Z","caller":"traceutil/trace.go:171","msg":"trace[2145120783] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"575.12055ms","start":"2026-05-08T10:27:14.301102Z","end":"2026-05-08T10:27:14.876223Z","steps":["trace[2145120783] 'process raft request' (duration: 574.860424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:14.876442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:14.301089Z","time spent":"575.254952ms","remote":"127.0.0.1:51622","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-05-08T10:27:15.275609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.184599ms","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-05-08T10:27:15.275657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.97023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:27:15.275715Z","caller":"traceutil/trace.go:171","msg":"trace[1838766903] 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:1287; }","duration":"112.357871ms","start":"2026-05-08T10:27:15.163338Z","end":"2026-05-08T10:27:15.275696Z","steps":["trace[1838766903] 'count revisions from in-memory index tree' (duration: 112.105757ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:15.275738Z","caller":"traceutil/trace.go:171","msg":"trace[1847367875] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1287; }","duration":"224.110832ms","start":"2026-05-08T10:27:15.051613Z","end":"2026-05-08T10:27:15.275723Z","steps":["trace[1847367875] 'count revisions from in-memory index tree' (duration: 223.876587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:15.275803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.606017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:27:15.275852Z","caller":"traceutil/trace.go:171","msg":"trace[489928471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"255.691489ms","start":"2026-05-08T10:27:15.020147Z","end":"2026-05-08T10:27:15.275838Z","steps":["trace[489928471] 'range keys from in-memory index tree' (duration: 255.483305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:16.074354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.55358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:27:16.074454Z","caller":"traceutil/trace.go:171","msg":"trace[774608655] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"138.636918ms","start":"2026-05-08T10:27:15.935801Z","end":"2026-05-08T10:27:16.074438Z","steps":["trace[774608655] 'read index received' (duration: 41.121µs)","trace[774608655] 'applied index is now lower than readState.Index' (duration: 138.594277ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:27:16.074599Z","caller":"traceutil/trace.go:171","msg":"trace[981652520] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"233.263074ms","start":"2026-05-08T10:27:15.841312Z","end":"2026-05-08T10:27:16.074575Z","steps":["trace[981652520] 'process raft request' (duration: 44.389168ms)","trace[981652520] 'compare' (duration: 188.456248ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:27:16.074734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.931944ms","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-05-08T10:27:16.074769Z","caller":"traceutil/trace.go:171","msg":"trace[1645438676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1291; }","duration":"139.005685ms","start":"2026-05-08T10:27:15.935752Z","end":"2026-05-08T10:27:16.074757Z","steps":["trace[1645438676] 'agreement among raft nodes before linearized reading' (duration: 138.728029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:16.964887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.729408ms","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-05-08T10:27:16.96496Z","caller":"traceutil/trace.go:171","msg":"trace[1999090853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"632.83719ms","start":"2026-05-08T10:27:16.332105Z","end":"2026-05-08T10:27:16.964943Z","steps":["trace[1999090853] 'range keys from in-memory index tree' (duration: 632.555645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:16.964998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:16.332093Z","time spent":"632.89624ms","remote":"127.0.0.1:51622","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-05-08T10:27:16.964984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.63069ms","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-05-08T10:27:16.965062Z","caller":"traceutil/trace.go:171","msg":"trace[73977815] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"664.771241ms","start":"2026-05-08T10:27:16.300273Z","end":"2026-05-08T10:27:16.965044Z","steps":["trace[73977815] 'range keys from in-memory index tree' (duration: 664.393885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:16.965101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:16.300255Z","time spent":"664.836943ms","remote":"127.0.0.1:51622","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-05-08T10:27:17.161994Z","caller":"traceutil/trace.go:171","msg":"trace[290587287] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"281.71351ms","start":"2026-05-08T10:27:16.880255Z","end":"2026-05-08T10:27:17.161968Z","steps":["trace[290587287] 'read index received' (duration: 281.667719ms)","trace[290587287] 'applied index is now lower than readState.Index' (duration: 44.581µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:27:17.162052Z","caller":"traceutil/trace.go:171","msg":"trace[1618533797] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"360.252482ms","start":"2026-05-08T10:27:16.801757Z","end":"2026-05-08T10:27:17.16201Z","steps":["trace[1618533797] 'process raft request' (duration: 360.016948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:17.162282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:16.801735Z","time spent":"360.428086ms","remote":"127.0.0.1:51622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:27:17.162287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.014785ms","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-05-08T10:27:17.162388Z","caller":"traceutil/trace.go:171","msg":"trace[128647958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1294; }","duration":"282.167188ms","start":"2026-05-08T10:27:16.880203Z","end":"2026-05-08T10:27:17.16237Z","steps":["trace[128647958] 'agreement among raft nodes before linearized reading' (duration: 281.894263ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:17.194729Z","caller":"traceutil/trace.go:171","msg":"trace[2040203041] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"226.104149ms","start":"2026-05-08T10:27:16.968598Z","end":"2026-05-08T10:27:17.194702Z","steps":["trace[2040203041] 'process raft request' (duration: 200.754361ms)","trace[2040203041] 'compare' (duration: 25.184085ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:27:17.194752Z","caller":"traceutil/trace.go:171","msg":"trace[92772095] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"225.732512ms","start":"2026-05-08T10:27:16.969Z","end":"2026-05-08T10:27:17.194732Z","steps":["trace[92772095] 'process raft request' (duration: 225.6338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:17.194873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.57104ms","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-05-08T10:27:17.194838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.200216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:27:17.194907Z","caller":"traceutil/trace.go:171","msg":"trace[1831211146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1297; }","duration":"314.658142ms","start":"2026-05-08T10:27:16.880242Z","end":"2026-05-08T10:27:17.1949Z","steps":["trace[1831211146] 'agreement among raft nodes before linearized reading' (duration: 314.500978ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:17.194922Z","caller":"traceutil/trace.go:171","msg":"trace[1845600021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"177.339829ms","start":"2026-05-08T10:27:17.017569Z","end":"2026-05-08T10:27:17.194909Z","steps":["trace[1845600021] 'agreement among raft nodes before linearized reading' (duration: 177.196636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:17.194931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:16.880221Z","time spent":"314.705362ms","remote":"127.0.0.1:51622","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-05-08T10:27:17.81024Z","caller":"traceutil/trace.go:171","msg":"trace[572153907] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"115.228246ms","start":"2026-05-08T10:27:17.694725Z","end":"2026-05-08T10:27:17.809954Z","steps":["trace[572153907] 'process raft request' (duration: 81.801065ms)","trace[572153907] 'compare' (duration: 33.323609ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:27:18.941794Z","caller":"traceutil/trace.go:171","msg":"trace[1047543963] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"330.429279ms","start":"2026-05-08T10:27:18.61134Z","end":"2026-05-08T10:27:18.941769Z","steps":["trace[1047543963] 'process raft request' (duration: 330.190865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:18.94202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:18.611316Z","time spent":"330.614672ms","remote":"127.0.0.1:51622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:27:19.0068Z","caller":"traceutil/trace.go:171","msg":"trace[32717508] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1378; }","duration":"124.95804ms","start":"2026-05-08T10:27:18.881809Z","end":"2026-05-08T10:27:19.006767Z","steps":["trace[32717508] 'read index received' (duration: 59.786669ms)","trace[32717508] 'applied index is now lower than readState.Index' (duration: 65.140481ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:27:19.00709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.259816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-6f6497f79c-x28rb\" ","response":"range_response_count:1 size:11919"} {"level":"info","ts":"2026-05-08T10:27:19.007142Z","caller":"traceutil/trace.go:171","msg":"trace[598532266] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-6f6497f79c-x28rb; range_end:; response_count:1; response_revision:1327; }","duration":"125.367068ms","start":"2026-05-08T10:27:18.881761Z","end":"2026-05-08T10:27:19.007128Z","steps":["trace[598532266] 'agreement among raft nodes before linearized reading' (duration: 125.113303ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:19.338674Z","caller":"traceutil/trace.go:171","msg":"trace[1102558294] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"264.973955ms","start":"2026-05-08T10:27:19.073678Z","end":"2026-05-08T10:27:19.338651Z","steps":["trace[1102558294] 'process raft request' (duration: 264.409273ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:19.34262Z","caller":"traceutil/trace.go:171","msg":"trace[2091785500] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1381; }","duration":"237.584817ms","start":"2026-05-08T10:27:19.105011Z","end":"2026-05-08T10:27:19.342596Z","steps":["trace[2091785500] 'read index received' (duration: 233.171913ms)","trace[2091785500] 'applied index is now lower than readState.Index' (duration: 4.411524ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:27:19.3427Z","caller":"traceutil/trace.go:171","msg":"trace[1721955820] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"268.696264ms","start":"2026-05-08T10:27:19.073982Z","end":"2026-05-08T10:27:19.342678Z","steps":["trace[1721955820] 'process raft request' (duration: 268.406958ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:19.342735Z","caller":"traceutil/trace.go:171","msg":"trace[1618788270] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"265.715998ms","start":"2026-05-08T10:27:19.07699Z","end":"2026-05-08T10:27:19.342706Z","steps":["trace[1618788270] 'process raft request' (duration: 265.542744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:19.342815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.788611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:27:19.342891Z","caller":"traceutil/trace.go:171","msg":"trace[1732626170] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1331; }","duration":"237.876142ms","start":"2026-05-08T10:27:19.104996Z","end":"2026-05-08T10:27:19.342872Z","steps":["trace[1732626170] 'agreement among raft nodes before linearized reading' (duration: 237.73828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:19.343904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.079589ms","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-05-08T10:27:19.343949Z","caller":"traceutil/trace.go:171","msg":"trace[1151072484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1331; }","duration":"145.13594ms","start":"2026-05-08T10:27:19.198801Z","end":"2026-05-08T10:27:19.343937Z","steps":["trace[1151072484] 'agreement among raft nodes before linearized reading' (duration: 144.983837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:19.344491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.676019ms","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-05-08T10:27:19.344556Z","caller":"traceutil/trace.go:171","msg":"trace[1450083742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1331; }","duration":"129.785091ms","start":"2026-05-08T10:27:19.214758Z","end":"2026-05-08T10:27:19.344543Z","steps":["trace[1450083742] 'agreement among raft nodes before linearized reading' (duration: 129.572947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:19.344655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.272104ms","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-05-08T10:27:19.344749Z","caller":"traceutil/trace.go:171","msg":"trace[1701831229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1331; }","duration":"144.384976ms","start":"2026-05-08T10:27:19.200351Z","end":"2026-05-08T10:27:19.344736Z","steps":["trace[1701831229] 'agreement among raft nodes before linearized reading' (duration: 144.208083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:19.344775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.932026ms","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-05-08T10:27:19.344858Z","caller":"traceutil/trace.go:171","msg":"trace[436901574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1331; }","duration":"146.044778ms","start":"2026-05-08T10:27:19.198787Z","end":"2026-05-08T10:27:19.344831Z","steps":["trace[436901574] 'agreement among raft nodes before linearized reading' (duration: 145.789743ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:25.485282Z","caller":"traceutil/trace.go:171","msg":"trace[615478393] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"102.967825ms","start":"2026-05-08T10:27:25.382291Z","end":"2026-05-08T10:27:25.485259Z","steps":["trace[615478393] 'process raft request' (duration: 102.795982ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:25.523223Z","caller":"traceutil/trace.go:171","msg":"trace[670194701] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"140.511343ms","start":"2026-05-08T10:27:25.382694Z","end":"2026-05-08T10:27:25.523205Z","steps":["trace[670194701] 'process raft request' (duration: 140.392981ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:25.523389Z","caller":"traceutil/trace.go:171","msg":"trace[371442002] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"140.691048ms","start":"2026-05-08T10:27:25.382688Z","end":"2026-05-08T10:27:25.523379Z","steps":["trace[371442002] 'process raft request' (duration: 140.480054ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:25.523644Z","caller":"traceutil/trace.go:171","msg":"trace[262446239] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"140.622015ms","start":"2026-05-08T10:27:25.382994Z","end":"2026-05-08T10:27:25.523616Z","steps":["trace[262446239] 'process raft request' (duration: 140.35222ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:48.619836Z","caller":"traceutil/trace.go:171","msg":"trace[50791621] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"112.578486ms","start":"2026-05-08T10:27:48.507237Z","end":"2026-05-08T10:27:48.619816Z","steps":["trace[50791621] 'process raft request' (duration: 111.241951ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:55.754807Z","caller":"traceutil/trace.go:171","msg":"trace[1877142272] linearizableReadLoop","detail":"{readStateIndex:1576; appliedIndex:1575; }","duration":"109.733303ms","start":"2026-05-08T10:27:55.645056Z","end":"2026-05-08T10:27:55.754789Z","steps":["trace[1877142272] 'read index received' (duration: 109.58569ms)","trace[1877142272] 'applied index is now lower than readState.Index' (duration: 146.853µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:27:55.754992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.924696ms","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-05-08T10:27:55.75501Z","caller":"traceutil/trace.go:171","msg":"trace[924357892] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"131.698636ms","start":"2026-05-08T10:27:55.623268Z","end":"2026-05-08T10:27:55.754967Z","steps":["trace[924357892] 'process raft request' (duration: 131.406301ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:55.75505Z","caller":"traceutil/trace.go:171","msg":"trace[19293790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1516; }","duration":"110.017418ms","start":"2026-05-08T10:27:55.645018Z","end":"2026-05-08T10:27:55.755036Z","steps":["trace[19293790] 'agreement among raft nodes before linearized reading' (duration: 109.858015ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:30:35.293113Z","caller":"traceutil/trace.go:171","msg":"trace[360155173] transaction","detail":"{read_only:false; response_revision:2355; number_of_response:1; }","duration":"101.359988ms","start":"2026-05-08T10:30:35.191724Z","end":"2026-05-08T10:30:35.293084Z","steps":["trace[360155173] 'process raft request' (duration: 101.123614ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:30:44.740508Z","caller":"traceutil/trace.go:171","msg":"trace[1204095954] transaction","detail":"{read_only:false; response_revision:2409; number_of_response:1; }","duration":"100.723305ms","start":"2026-05-08T10:30:44.63975Z","end":"2026-05-08T10:30:44.740474Z","steps":["trace[1204095954] 'process raft request' (duration: 100.393819ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:30:44.740778Z","caller":"traceutil/trace.go:171","msg":"trace[662795208] transaction","detail":"{read_only:false; response_revision:2408; number_of_response:1; }","duration":"101.314898ms","start":"2026-05-08T10:30:44.639445Z","end":"2026-05-08T10:30:44.74076Z","steps":["trace[662795208] 'process raft request' (duration: 61.120957ms)","trace[662795208] 'compare' (duration: 39.438376ms)"],"step_count":2}