{"level":"warn","ts":"2026-05-06T00:45:05.770716Z","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-06T00:45:05.770817Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.86: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.86:2380","--initial-cluster=instance=https://199.19.213.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.86: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-06T00:45:05.770886Z","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-06T00:45:05.770896Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-05-06T00:45:05.770911Z","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-06T00:45:05.771522Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"]} {"level":"info","ts":"2026-05-06T00:45:05.771597Z","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.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.86: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-06T00:45:05.776233Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.402779ms"} {"level":"info","ts":"2026-05-06T00:45:05.78427Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"849a6ffb5e81c109","cluster-id":"d4d86b1dd8829199"} {"level":"info","ts":"2026-05-06T00:45:05.784412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T00:45:05.785132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 0"} {"level":"info","ts":"2026-05-06T00:45:05.785174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 849a6ffb5e81c109 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T00:45:05.7852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 1"} {"level":"info","ts":"2026-05-06T00:45:05.785281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"warn","ts":"2026-05-06T00:45:05.797816Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T00:45:05.80332Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T00:45:05.805089Z","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-06T00:45:05.808088Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"849a6ffb5e81c109","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T00:45:05.808281Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"849a6ffb5e81c109","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T00:45:05.80837Z","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-06T00:45:05.808421Z","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-06T00:45:05.80843Z","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-06T00:45:05.809404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"info","ts":"2026-05-06T00:45:05.809701Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","added-peer-id":"849a6ffb5e81c109","added-peer-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-05-06T00:45:05.815625Z","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-06T00:45:05.816004Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"849a6ffb5e81c109","initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T00:45:05.816033Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T00:45:05.816559Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-05-06T00:45:05.816602Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-05-06T00:45:05.885879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T00:45:05.885954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T00:45:05.885989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgPreVoteResp from 849a6ffb5e81c109 at term 1"} {"level":"info","ts":"2026-05-06T00:45:05.886014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became candidate at term 2"} {"level":"info","ts":"2026-05-06T00:45:05.886261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgVoteResp from 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-05-06T00:45:05.886294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became leader at term 2"} {"level":"info","ts":"2026-05-06T00:45:05.88642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 849a6ffb5e81c109 elected leader 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-05-06T00:45:05.88773Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:45:05.888466Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"849a6ffb5e81c109","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.86:2379]}","request-path":"/0/members/849a6ffb5e81c109/attributes","cluster-id":"d4d86b1dd8829199","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T00:45:05.888478Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:45:05.888555Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:45:05.88888Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:45:05.888949Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T00:45:05.889018Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T00:45:05.889048Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:45:05.889121Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:45:05.892298Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T00:45:05.893856Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.86:2379"} {"level":"info","ts":"2026-05-06T00:45:08.218644Z","caller":"traceutil/trace.go:171","msg":"trace[284181809] linearizableReadLoop","detail":"{readStateIndex:92; appliedIndex:91; }","duration":"263.085502ms","start":"2026-05-06T00:45:07.955534Z","end":"2026-05-06T00:45:08.218619Z","steps":["trace[284181809] 'read index received' (duration: 183.75098ms)","trace[284181809] 'applied index is now lower than readState.Index' (duration: 79.333842ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:08.218717Z","caller":"traceutil/trace.go:171","msg":"trace[2030016990] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"268.627354ms","start":"2026-05-06T00:45:07.950058Z","end":"2026-05-06T00:45:08.218686Z","steps":["trace[2030016990] 'process raft request' (duration: 189.225979ms)","trace[2030016990] 'compare' (duration: 79.21451ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:08.218798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.243536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18acd3529cd37292\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-05-06T00:45:08.218849Z","caller":"traceutil/trace.go:171","msg":"trace[1904518626] range","detail":"{range_begin:/registry/events/default/instance.18acd3529cd37292; range_end:; response_count:1; response_revision:87; }","duration":"263.339518ms","start":"2026-05-06T00:45:07.955501Z","end":"2026-05-06T00:45:08.21884Z","steps":["trace[1904518626] 'agreement among raft nodes before linearized reading' (duration: 263.215785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:09.523685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.528694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:45:09.523826Z","caller":"traceutil/trace.go:171","msg":"trace[484734991] linearizableReadLoop","detail":"{readStateIndex:136; appliedIndex:135; }","duration":"866.21531ms","start":"2026-05-06T00:45:08.65758Z","end":"2026-05-06T00:45:09.523795Z","steps":["trace[484734991] 'read index received' (duration: 15.700254ms)","trace[484734991] 'applied index is now lower than readState.Index' (duration: 850.513116ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:09.523872Z","caller":"traceutil/trace.go:171","msg":"trace[1566141375] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"870.223736ms","start":"2026-05-06T00:45:08.6536Z","end":"2026-05-06T00:45:09.523824Z","steps":["trace[1566141375] 'process raft request' (duration: 19.761071ms)","trace[1566141375] 'compare' (duration: 849.345401ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:09.524047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.450595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:45:09.524041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:08.653583Z","time spent":"870.382239ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:45:09.524197Z","caller":"traceutil/trace.go:171","msg":"trace[1993662315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:131; }","duration":"866.554507ms","start":"2026-05-06T00:45:08.657565Z","end":"2026-05-06T00:45:09.524119Z","steps":["trace[1993662315] 'agreement among raft nodes before linearized reading' (duration: 866.311283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:09.524248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:08.657515Z","time spent":"866.724181ms","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:45:09.900913Z","caller":"traceutil/trace.go:171","msg":"trace[1544343150] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"104.934089ms","start":"2026-05-06T00:45:09.795955Z","end":"2026-05-06T00:45:09.900889Z","steps":["trace[1544343150] 'process raft request' (duration: 41.926788ms)","trace[1544343150] 'compare' (duration: 62.887159ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:10.139587Z","caller":"traceutil/trace.go:171","msg":"trace[1934909945] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"163.145962ms","start":"2026-05-06T00:45:09.97642Z","end":"2026-05-06T00:45:10.139566Z","steps":["trace[1934909945] 'process raft request' (duration: 80.889418ms)","trace[1934909945] 'compare' (duration: 82.132392ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:10.444128Z","caller":"traceutil/trace.go:171","msg":"trace[573778334] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"215.220335ms","start":"2026-05-06T00:45:10.228875Z","end":"2026-05-06T00:45:10.444095Z","steps":["trace[573778334] 'process raft request' (duration: 140.576958ms)","trace[573778334] 'compare' (duration: 74.521024ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:11.598362Z","caller":"traceutil/trace.go:171","msg":"trace[990647241] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"260.252992ms","start":"2026-05-06T00:45:11.338071Z","end":"2026-05-06T00:45:11.598324Z","steps":["trace[990647241] 'process raft request' (duration: 256.136049ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:11.660565Z","caller":"traceutil/trace.go:171","msg":"trace[606113941] linearizableReadLoop","detail":"{readStateIndex:216; appliedIndex:214; }","duration":"140.841012ms","start":"2026-05-06T00:45:11.519701Z","end":"2026-05-06T00:45:11.660542Z","steps":["trace[606113941] 'read index received' (duration: 74.677111ms)","trace[606113941] 'applied index is now lower than readState.Index' (duration: 66.162501ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:11.660669Z","caller":"traceutil/trace.go:171","msg":"trace[1028247535] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"303.319535ms","start":"2026-05-06T00:45:11.357342Z","end":"2026-05-06T00:45:11.660662Z","steps":["trace[1028247535] 'process raft request' (duration: 303.05984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:11.660751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:11.357313Z","time spent":"303.379355ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:45:11.660847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.147049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:45:11.660847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.268802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:11.660967Z","caller":"traceutil/trace.go:171","msg":"trace[1622212916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:211; }","duration":"141.244891ms","start":"2026-05-06T00:45:11.519686Z","end":"2026-05-06T00:45:11.660931Z","steps":["trace[1622212916] 'agreement among raft nodes before linearized reading' (duration: 141.081258ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:11.660973Z","caller":"traceutil/trace.go:171","msg":"trace[1257711248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:211; }","duration":"102.453895ms","start":"2026-05-06T00:45:11.558502Z","end":"2026-05-06T00:45:11.660956Z","steps":["trace[1257711248] 'agreement among raft nodes before linearized reading' (duration: 102.20776ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:11.785421Z","caller":"traceutil/trace.go:171","msg":"trace[2044159536] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"119.719345ms","start":"2026-05-06T00:45:11.665661Z","end":"2026-05-06T00:45:11.78538Z","steps":["trace[2044159536] 'process raft request' (duration: 119.562052ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:11.785595Z","caller":"traceutil/trace.go:171","msg":"trace[470171812] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"121.395388ms","start":"2026-05-06T00:45:11.664175Z","end":"2026-05-06T00:45:11.78557Z","steps":["trace[470171812] 'process raft request' (duration: 110.58821ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:12.026142Z","caller":"traceutil/trace.go:171","msg":"trace[1877200178] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"160.077746ms","start":"2026-05-06T00:45:11.866023Z","end":"2026-05-06T00:45:12.026101Z","steps":["trace[1877200178] 'process raft request' (duration: 114.943399ms)","trace[1877200178] 'compare' (duration: 44.902433ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:13.975399Z","caller":"traceutil/trace.go:171","msg":"trace[1900270654] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:296; }","duration":"165.088763ms","start":"2026-05-06T00:45:13.810286Z","end":"2026-05-06T00:45:13.975374Z","steps":["trace[1900270654] 'read index received' (duration: 86.78349ms)","trace[1900270654] 'applied index is now lower than readState.Index' (duration: 78.263582ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:13.975501Z","caller":"traceutil/trace.go:171","msg":"trace[1230519657] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"172.454409ms","start":"2026-05-06T00:45:13.803008Z","end":"2026-05-06T00:45:13.975463Z","steps":["trace[1230519657] 'process raft request' (duration: 94.109475ms)","trace[1230519657] 'compare' (duration: 78.11368ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:13.975545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.239907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:13.975694Z","caller":"traceutil/trace.go:171","msg":"trace[327402805] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:291; }","duration":"165.442702ms","start":"2026-05-06T00:45:13.81023Z","end":"2026-05-06T00:45:13.975672Z","steps":["trace[327402805] 'agreement among raft nodes before linearized reading' (duration: 165.261788ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:13.97616Z","caller":"traceutil/trace.go:171","msg":"trace[1766419364] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"152.324983ms","start":"2026-05-06T00:45:13.823821Z","end":"2026-05-06T00:45:13.976146Z","steps":["trace[1766419364] 'process raft request' (duration: 152.15956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:14.523724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822624380058544,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:15.54893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.163408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-06T00:45:15.549235Z","caller":"traceutil/trace.go:171","msg":"trace[1316855182] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"1.526766248s","start":"2026-05-06T00:45:14.022423Z","end":"2026-05-06T00:45:15.549189Z","steps":["trace[1316855182] 'process raft request' (duration: 989.271084ms)","trace[1316855182] 'compare' (duration: 537.106887ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:15.549398Z","caller":"traceutil/trace.go:171","msg":"trace[1930261954] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"1.526337292s","start":"2026-05-06T00:45:14.022956Z","end":"2026-05-06T00:45:15.549293Z","steps":["trace[1930261954] 'process raft request' (duration: 1.52630397s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:15.549478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:14.02241Z","time spent":"1.526932361s","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:45:15.5499Z","caller":"traceutil/trace.go:171","msg":"trace[1748877330] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"1.526989943s","start":"2026-05-06T00:45:14.022898Z","end":"2026-05-06T00:45:15.549888Z","steps":["trace[1748877330] 'process raft request' (duration: 1.526333811s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:15.549989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:14.02289Z","time spent":"1.527055634s","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:45:15.549523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:14.022949Z","time spent":"1.526538995s","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:45:15.549411Z","caller":"traceutil/trace.go:171","msg":"trace[953205382] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"1.526882551s","start":"2026-05-06T00:45:14.02245Z","end":"2026-05-06T00:45:15.549332Z","steps":["trace[953205382] 'process raft request' (duration: 1.526677647s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:15.550868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:14.022442Z","time spent":"1.528372439s","remote":"127.0.0.1:40842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3200,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:45:15.611212Z","caller":"traceutil/trace.go:171","msg":"trace[1758559271] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"673.010725ms","start":"2026-05-06T00:45:14.938177Z","end":"2026-05-06T00:45:15.611188Z","steps":["trace[1758559271] 'process raft request' (duration: 672.954554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:15.611353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:14.93816Z","time spent":"673.133417ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:45:15.611529Z","caller":"traceutil/trace.go:171","msg":"trace[147825953] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:312; }","duration":"1.587966339s","start":"2026-05-06T00:45:14.023529Z","end":"2026-05-06T00:45:15.611495Z","steps":["trace[147825953] 'read index received' (duration: 988.176645ms)","trace[147825953] 'applied index is now lower than readState.Index' (duration: 599.788404ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:15.611221Z","caller":"traceutil/trace.go:171","msg":"trace[1039508391] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"1.582779019s","start":"2026-05-06T00:45:14.028419Z","end":"2026-05-06T00:45:15.611198Z","steps":["trace[1039508391] 'process raft request' (duration: 1.582548765s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:15.611735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.588185384s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:15.611808Z","caller":"traceutil/trace.go:171","msg":"trace[387519769] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:0; response_revision:307; }","duration":"1.588286265s","start":"2026-05-06T00:45:14.023501Z","end":"2026-05-06T00:45:15.611787Z","steps":["trace[387519769] 'agreement among raft nodes before linearized reading' (duration: 1.588139373s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:15.611853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:14.023489Z","time spent":"1.588352996s","remote":"127.0.0.1:40592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" "} {"level":"warn","ts":"2026-05-06T00:45:15.611914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:14.028352Z","time spent":"1.58327125s","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4182,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:15.612043Z","caller":"traceutil/trace.go:171","msg":"trace[259723580] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"673.612927ms","start":"2026-05-06T00:45:14.938416Z","end":"2026-05-06T00:45:15.612029Z","steps":["trace[259723580] 'process raft request' (duration: 672.74678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:15.612128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:14.938405Z","time spent":"673.685498ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:45:15.61175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.106726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-05-06T00:45:15.612302Z","caller":"traceutil/trace.go:171","msg":"trace[25384364] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:307; }","duration":"675.711617ms","start":"2026-05-06T00:45:14.936576Z","end":"2026-05-06T00:45:15.612288Z","steps":["trace[25384364] 'agreement among raft nodes before linearized reading' (duration: 675.073375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:15.612349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:14.936556Z","time spent":"675.779209ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5537,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-05-06T00:45:15.6127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.243855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-06T00:45:15.612923Z","caller":"traceutil/trace.go:171","msg":"trace[305349195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:307; }","duration":"435.483139ms","start":"2026-05-06T00:45:15.177417Z","end":"2026-05-06T00:45:15.6129Z","steps":["trace[305349195] 'agreement among raft nodes before linearized reading' (duration: 435.223064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:15.613119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:15.1774Z","time spent":"435.700524ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T00:45:27.733864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.25539ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-06T00:45:27.733958Z","caller":"traceutil/trace.go:171","msg":"trace[991040961] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:440; }","duration":"108.892508ms","start":"2026-05-06T00:45:27.625049Z","end":"2026-05-06T00:45:27.733942Z","steps":["trace[991040961] 'read index received' (duration: 23.311µs)","trace[991040961] 'applied index is now lower than readState.Index' (duration: 108.867887ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:27.734064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.006709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:27.734093Z","caller":"traceutil/trace.go:171","msg":"trace[646581091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"109.04407ms","start":"2026-05-06T00:45:27.625039Z","end":"2026-05-06T00:45:27.734083Z","steps":["trace[646581091] 'agreement among raft nodes before linearized reading' (duration: 108.974089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:28.005509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.984175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T00:45:28.005616Z","caller":"traceutil/trace.go:171","msg":"trace[1270021281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:428; }","duration":"132.514052ms","start":"2026-05-06T00:45:27.873083Z","end":"2026-05-06T00:45:28.005597Z","steps":["trace[1270021281] 'range keys from in-memory index tree' (duration: 131.845993ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:28.154282Z","caller":"traceutil/trace.go:171","msg":"trace[638254369] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"144.25025ms","start":"2026-05-06T00:45:28.009994Z","end":"2026-05-06T00:45:28.154244Z","steps":["trace[638254369] 'process raft request' (duration: 144.055908ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:32.154085Z","caller":"traceutil/trace.go:171","msg":"trace[634426047] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"351.1573ms","start":"2026-05-06T00:45:31.802901Z","end":"2026-05-06T00:45:32.154059Z","steps":["trace[634426047] 'read index received' (duration: 350.953606ms)","trace[634426047] 'applied index is now lower than readState.Index' (duration: 202.964µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:32.154191Z","caller":"traceutil/trace.go:171","msg":"trace[1808169467] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"367.517768ms","start":"2026-05-06T00:45:31.786647Z","end":"2026-05-06T00:45:32.154165Z","steps":["trace[1808169467] 'process raft request' (duration: 367.290104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:32.154261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.452434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-06T00:45:32.154502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:31.786624Z","time spent":"367.802672ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:32.154509Z","caller":"traceutil/trace.go:171","msg":"trace[1310476355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:436; }","duration":"351.766769ms","start":"2026-05-06T00:45:31.802728Z","end":"2026-05-06T00:45:32.154494Z","steps":["trace[1310476355] 'agreement among raft nodes before linearized reading' (duration: 351.428694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:32.154566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:31.802702Z","time spent":"351.85115ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T00:45:32.727492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822624380058840,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:33.228385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822624380058840,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:33.422912Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.263468337s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T00:45:33.533049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.31577ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:33.533214Z","caller":"traceutil/trace.go:171","msg":"trace[752559933] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:436; }","duration":"677.497463ms","start":"2026-05-06T00:45:32.855689Z","end":"2026-05-06T00:45:33.533186Z","steps":["trace[752559933] 'range keys from in-memory index tree' (duration: 677.29804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:33.533301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374029545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-06T00:45:33.533525Z","caller":"traceutil/trace.go:171","msg":"trace[1164952985] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:436; }","duration":"1.374295198s","start":"2026-05-06T00:45:32.159196Z","end":"2026-05-06T00:45:33.533491Z","steps":["trace[1164952985] 'range keys from in-memory index tree' (duration: 1.373909574s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:33.533579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:32.159176Z","time spent":"1.37439081s","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.86\" "} {"level":"warn","ts":"2026-05-06T00:45:33.534138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.876513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:45:33.534238Z","caller":"traceutil/trace.go:171","msg":"trace[1108888158] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:451; }","duration":"1.30700189s","start":"2026-05-06T00:45:32.227219Z","end":"2026-05-06T00:45:33.534221Z","steps":["trace[1108888158] 'read index received' (duration: 1.195868224s)","trace[1108888158] 'applied index is now lower than readState.Index' (duration: 111.132236ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:33.534383Z","caller":"traceutil/trace.go:171","msg":"trace[1393219385] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"1.374981089s","start":"2026-05-06T00:45:32.15934Z","end":"2026-05-06T00:45:33.534321Z","steps":["trace[1393219385] 'process raft request' (duration: 1.263797591s)","trace[1393219385] 'compare' (duration: 110.604709ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:33.53451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307284974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-06T00:45:33.534501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301442847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:45:33.534564Z","caller":"traceutil/trace.go:171","msg":"trace[1619371035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:437; }","duration":"1.307372517s","start":"2026-05-06T00:45:32.227173Z","end":"2026-05-06T00:45:33.534545Z","steps":["trace[1619371035] 'agreement among raft nodes before linearized reading' (duration: 1.307214844s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:33.534577Z","caller":"traceutil/trace.go:171","msg":"trace[388417101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:437; }","duration":"1.301530679s","start":"2026-05-06T00:45:32.233024Z","end":"2026-05-06T00:45:33.534555Z","steps":["trace[388417101] 'agreement among raft nodes before linearized reading' (duration: 1.301389216s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:33.534599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:32.159315Z","time spent":"1.375175332s","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:45:33.53461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:32.227142Z","time spent":"1.307456477s","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:45:33.534616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:32.233012Z","time spent":"1.301593849s","remote":"127.0.0.1:40650","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-06T00:45:33.534602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.714845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:33.534731Z","caller":"traceutil/trace.go:171","msg":"trace[2036413797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:437; }","duration":"909.856498ms","start":"2026-05-06T00:45:32.624831Z","end":"2026-05-06T00:45:33.534688Z","steps":["trace[2036413797] 'agreement among raft nodes before linearized reading' (duration: 909.643414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:33.534798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:32.624793Z","time spent":"909.990279ms","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:45:39.432077Z","caller":"traceutil/trace.go:171","msg":"trace[365061378] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"172.057319ms","start":"2026-05-06T00:45:39.259998Z","end":"2026-05-06T00:45:39.432055Z","steps":["trace[365061378] 'process raft request' (duration: 171.822805ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:40.449776Z","caller":"traceutil/trace.go:171","msg":"trace[1895352365] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"259.148952ms","start":"2026-05-06T00:45:40.190598Z","end":"2026-05-06T00:45:40.449747Z","steps":["trace[1895352365] 'process raft request' (duration: 258.9918ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:51.894211Z","caller":"traceutil/trace.go:171","msg":"trace[882233649] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"174.104111ms","start":"2026-05-06T00:45:51.720067Z","end":"2026-05-06T00:45:51.894171Z","steps":["trace[882233649] 'process raft request' (duration: 161.865799ms)","trace[882233649] 'compare' (duration: 11.886378ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:51.899667Z","caller":"traceutil/trace.go:171","msg":"trace[1981606208] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"136.533763ms","start":"2026-05-06T00:45:51.763097Z","end":"2026-05-06T00:45:51.89963Z","steps":["trace[1981606208] 'process raft request' (duration: 131.862345ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:51.899673Z","caller":"traceutil/trace.go:171","msg":"trace[1179588676] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:529; }","duration":"174.845451ms","start":"2026-05-06T00:45:51.724741Z","end":"2026-05-06T00:45:51.899586Z","steps":["trace[1179588676] 'read index received' (duration: 51.778976ms)","trace[1179588676] 'applied index is now lower than readState.Index' (duration: 123.065155ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:51.899843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.081454ms","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-06T00:45:51.899889Z","caller":"traceutil/trace.go:171","msg":"trace[1119984966] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"175.175456ms","start":"2026-05-06T00:45:51.724702Z","end":"2026-05-06T00:45:51.899878Z","steps":["trace[1119984966] 'agreement among raft nodes before linearized reading' (duration: 175.019503ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:52.308402Z","caller":"traceutil/trace.go:171","msg":"trace[1228298536] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"107.642343ms","start":"2026-05-06T00:45:52.200736Z","end":"2026-05-06T00:45:52.308379Z","steps":["trace[1228298536] 'process raft request' (duration: 107.509922ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:52.308605Z","caller":"traceutil/trace.go:171","msg":"trace[1090585979] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"106.432367ms","start":"2026-05-06T00:45:52.202162Z","end":"2026-05-06T00:45:52.308594Z","steps":["trace[1090585979] 'process raft request' (duration: 106.134314ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:52.308859Z","caller":"traceutil/trace.go:171","msg":"trace[221893379] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"103.788655ms","start":"2026-05-06T00:45:52.205046Z","end":"2026-05-06T00:45:52.308835Z","steps":["trace[221893379] 'process raft request' (duration: 103.279308ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:52.308897Z","caller":"traceutil/trace.go:171","msg":"trace[534704556] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:550; }","duration":"104.885109ms","start":"2026-05-06T00:45:52.203999Z","end":"2026-05-06T00:45:52.308884Z","steps":["trace[534704556] 'read index received' (duration: 3.979389ms)","trace[534704556] 'applied index is now lower than readState.Index' (duration: 100.90445ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:52.308903Z","caller":"traceutil/trace.go:171","msg":"trace[972962417] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"109.85115ms","start":"2026-05-06T00:45:52.199001Z","end":"2026-05-06T00:45:52.308852Z","steps":["trace[972962417] 'process raft request' (duration: 109.146652ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:52.308965Z","caller":"traceutil/trace.go:171","msg":"trace[516444090] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"103.733833ms","start":"2026-05-06T00:45:52.205218Z","end":"2026-05-06T00:45:52.308952Z","steps":["trace[516444090] 'process raft request' (duration: 103.463919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:52.309168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.145321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-05-06T00:45:52.309239Z","caller":"traceutil/trace.go:171","msg":"trace[7036597] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:535; }","duration":"105.256082ms","start":"2026-05-06T00:45:52.203965Z","end":"2026-05-06T00:45:52.309221Z","steps":["trace[7036597] 'agreement among raft nodes before linearized reading' (duration: 104.995819ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:52.311647Z","caller":"traceutil/trace.go:171","msg":"trace[549258793] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"103.655544ms","start":"2026-05-06T00:45:52.207965Z","end":"2026-05-06T00:45:52.31162Z","steps":["trace[549258793] 'process raft request' (duration: 101.450835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:54.581555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.922732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-06T00:45:54.581673Z","caller":"traceutil/trace.go:171","msg":"trace[1700320966] transaction","detail":"{read_only:false; number_of_response:0; response_revision:567; }","duration":"673.143908ms","start":"2026-05-06T00:45:53.908513Z","end":"2026-05-06T00:45:54.581657Z","steps":["trace[1700320966] 'process raft request' (duration: 89.046364ms)","trace[1700320966] 'compare' (duration: 583.850311ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:54.581726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:53.908474Z","time spent":"673.23196ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:45:54.773685Z","caller":"traceutil/trace.go:171","msg":"trace[1541217087] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"859.832675ms","start":"2026-05-06T00:45:53.913831Z","end":"2026-05-06T00:45:54.773663Z","steps":["trace[1541217087] 'process raft request' (duration: 859.611333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:54.773806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:53.913807Z","time spent":"859.933518ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:54.773808Z","caller":"traceutil/trace.go:171","msg":"trace[1827747217] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"855.872258ms","start":"2026-05-06T00:45:53.91792Z","end":"2026-05-06T00:45:54.773792Z","steps":["trace[1827747217] 'process raft request' (duration: 855.668154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:54.773907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:53.917911Z","time spent":"855.949048ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:54.774087Z","caller":"traceutil/trace.go:171","msg":"trace[602502211] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"849.672751ms","start":"2026-05-06T00:45:53.924389Z","end":"2026-05-06T00:45:54.774062Z","steps":["trace[602502211] 'process raft request' (duration: 849.293926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:54.774255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:53.924371Z","time spent":"849.786612ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:54.774914Z","caller":"traceutil/trace.go:171","msg":"trace[1785166881] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"839.289763ms","start":"2026-05-06T00:45:53.9356Z","end":"2026-05-06T00:45:54.77489Z","steps":["trace[1785166881] 'process raft request' (duration: 839.110381ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:54.774954Z","caller":"traceutil/trace.go:171","msg":"trace[184562434] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"847.629526ms","start":"2026-05-06T00:45:53.9273Z","end":"2026-05-06T00:45:54.774929Z","steps":["trace[184562434] 'process raft request' (duration: 847.232771ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:54.775009Z","caller":"traceutil/trace.go:171","msg":"trace[2044067176] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:592; }","duration":"857.150062ms","start":"2026-05-06T00:45:53.917845Z","end":"2026-05-06T00:45:54.774995Z","steps":["trace[2044067176] 'read index received' (duration: 79.793701ms)","trace[2044067176] 'applied index is now lower than readState.Index' (duration: 777.355251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:54.775047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:53.935594Z","time spent":"839.385854ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:45:54.775073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:53.927294Z","time spent":"847.719487ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:54.775139Z","caller":"traceutil/trace.go:171","msg":"trace[2023141827] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"838.439143ms","start":"2026-05-06T00:45:53.936691Z","end":"2026-05-06T00:45:54.77513Z","steps":["trace[2023141827] 'process raft request' (duration: 838.084278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:54.775208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:53.936682Z","time spent":"838.486023ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:54.775223Z","caller":"traceutil/trace.go:171","msg":"trace[2108119951] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"848.097062ms","start":"2026-05-06T00:45:53.927111Z","end":"2026-05-06T00:45:54.775208Z","steps":["trace[2108119951] 'process raft request' (duration: 846.871537ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:54.775271Z","caller":"traceutil/trace.go:171","msg":"trace[1387716402] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"839.924501ms","start":"2026-05-06T00:45:53.93534Z","end":"2026-05-06T00:45:54.775264Z","steps":["trace[1387716402] 'process raft request' (duration: 839.314594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:54.775277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:53.927082Z","time spent":"848.169433ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:45:54.775309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:53.93531Z","time spent":"839.983302ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:45:54.775482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.627628ms","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-06T00:45:54.775546Z","caller":"traceutil/trace.go:171","msg":"trace[1513128997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"857.718249ms","start":"2026-05-06T00:45:53.917813Z","end":"2026-05-06T00:45:54.775531Z","steps":["trace[1513128997] 'agreement among raft nodes before linearized reading' (duration: 857.529997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:54.775588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:53.917783Z","time spent":"857.796631ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T00:45:54.780853Z","caller":"traceutil/trace.go:171","msg":"trace[943384947] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"722.226799ms","start":"2026-05-06T00:45:54.058596Z","end":"2026-05-06T00:45:54.780823Z","steps":["trace[943384947] 'process raft request' (duration: 721.752733ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:54.780888Z","caller":"traceutil/trace.go:171","msg":"trace[2017923944] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"339.107386ms","start":"2026-05-06T00:45:54.441763Z","end":"2026-05-06T00:45:54.78087Z","steps":["trace[2017923944] 'process raft request' (duration: 339.001095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:54.780973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:54.44174Z","time spent":"339.196597ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:45:54.780973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:54.058576Z","time spent":"722.338321ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:45:54.781294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.089401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:45:54.781409Z","caller":"traceutil/trace.go:171","msg":"trace[1017198877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"255.180552ms","start":"2026-05-06T00:45:54.526184Z","end":"2026-05-06T00:45:54.781364Z","steps":["trace[1017198877] 'agreement among raft nodes before linearized reading' (duration: 254.629576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:54.781416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.552221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-05-06T00:45:54.781614Z","caller":"traceutil/trace.go:171","msg":"trace[1184208101] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:577; }","duration":"198.788025ms","start":"2026-05-06T00:45:54.58275Z","end":"2026-05-06T00:45:54.781538Z","steps":["trace[1184208101] 'agreement among raft nodes before linearized reading' (duration: 198.172457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:55.77719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.9605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:45:55.777332Z","caller":"traceutil/trace.go:171","msg":"trace[426030510] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"477.980109ms","start":"2026-05-06T00:45:55.299328Z","end":"2026-05-06T00:45:55.777308Z","steps":["trace[426030510] 'process raft request' (duration: 65.802127ms)","trace[426030510] 'compare' (duration: 411.782217ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:55.777418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:55.299303Z","time spent":"478.06871ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:45:56.109391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.249507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:45:56.109523Z","caller":"traceutil/trace.go:171","msg":"trace[288972149] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"804.025819ms","start":"2026-05-06T00:45:55.305472Z","end":"2026-05-06T00:45:56.109498Z","steps":["trace[288972149] 'process raft request' (duration: 669.59923ms)","trace[288972149] 'compare' (duration: 130.909637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:56.109613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:55.305452Z","time spent":"804.11887ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:56.118849Z","caller":"traceutil/trace.go:171","msg":"trace[921382501] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"490.088716ms","start":"2026-05-06T00:45:55.628717Z","end":"2026-05-06T00:45:56.118806Z","steps":["trace[921382501] 'read index received' (duration: 346.361125ms)","trace[921382501] 'applied index is now lower than readState.Index' (duration: 143.726911ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:56.11914Z","caller":"traceutil/trace.go:171","msg":"trace[391250519] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"683.609899ms","start":"2026-05-06T00:45:55.435519Z","end":"2026-05-06T00:45:56.119129Z","steps":["trace[391250519] 'process raft request' (duration: 683.078423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:56.119229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:55.435492Z","time spent":"683.668169ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:45:56.119353Z","caller":"traceutil/trace.go:171","msg":"trace[639950782] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"328.141364ms","start":"2026-05-06T00:45:55.791204Z","end":"2026-05-06T00:45:56.119345Z","steps":["trace[639950782] 'process raft request' (duration: 327.549587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:56.119399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:55.791192Z","time spent":"328.175905ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20842,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:45:56.119506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.787144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:56.119525Z","caller":"traceutil/trace.go:171","msg":"trace[1677055305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"490.834994ms","start":"2026-05-06T00:45:55.628684Z","end":"2026-05-06T00:45:56.119519Z","steps":["trace[1677055305] 'agreement among raft nodes before linearized reading' (duration: 490.798824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:56.119541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:55.628669Z","time spent":"490.869244ms","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:45:56.119692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.608362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T00:45:56.119709Z","caller":"traceutil/trace.go:171","msg":"trace[1743111241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"329.656433ms","start":"2026-05-06T00:45:55.790047Z","end":"2026-05-06T00:45:56.119704Z","steps":["trace[1743111241] 'agreement among raft nodes before linearized reading' (duration: 329.577872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:56.119728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:55.79003Z","time spent":"329.693563ms","remote":"127.0.0.1:40650","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-06T00:45:56.603615Z","caller":"traceutil/trace.go:171","msg":"trace[938858566] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"111.969687ms","start":"2026-05-06T00:45:56.491624Z","end":"2026-05-06T00:45:56.603594Z","steps":["trace[938858566] 'read index received' (duration: 111.708054ms)","trace[938858566] 'applied index is now lower than readState.Index' (duration: 260.803µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:56.603665Z","caller":"traceutil/trace.go:171","msg":"trace[811507052] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"119.220384ms","start":"2026-05-06T00:45:56.484417Z","end":"2026-05-06T00:45:56.603638Z","steps":["trace[811507052] 'process raft request' (duration: 118.976011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:56.603818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.17633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-05-06T00:45:56.603861Z","caller":"traceutil/trace.go:171","msg":"trace[1015315576] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:625; }","duration":"112.236731ms","start":"2026-05-06T00:45:56.491612Z","end":"2026-05-06T00:45:56.603849Z","steps":["trace[1015315576] 'agreement among raft nodes before linearized reading' (duration: 112.067918ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:56.723364Z","caller":"traceutil/trace.go:171","msg":"trace[1407952772] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"100.485079ms","start":"2026-05-06T00:45:56.622808Z","end":"2026-05-06T00:45:56.723293Z","steps":["trace[1407952772] 'process raft request' (duration: 90.769212ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:56.878Z","caller":"traceutil/trace.go:171","msg":"trace[2125905874] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"100.287276ms","start":"2026-05-06T00:45:56.777677Z","end":"2026-05-06T00:45:56.877964Z","steps":["trace[2125905874] 'read index received' (duration: 87.678644ms)","trace[2125905874] 'applied index is now lower than readState.Index' (duration: 12.607352ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:56.87815Z","caller":"traceutil/trace.go:171","msg":"trace[1064656029] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"103.15368ms","start":"2026-05-06T00:45:56.77498Z","end":"2026-05-06T00:45:56.878133Z","steps":["trace[1064656029] 'process raft request' (duration: 90.475947ms)","trace[1064656029] 'compare' (duration: 12.37053ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:56.878454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.778302ms","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-06T00:45:56.878479Z","caller":"traceutil/trace.go:171","msg":"trace[1963089541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"100.847352ms","start":"2026-05-06T00:45:56.777626Z","end":"2026-05-06T00:45:56.878474Z","steps":["trace[1963089541] 'agreement among raft nodes before linearized reading' (duration: 100.784242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:02.092134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.844655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:46:02.092279Z","caller":"traceutil/trace.go:171","msg":"trace[1292598655] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"952.947403ms","start":"2026-05-06T00:46:01.13931Z","end":"2026-05-06T00:46:02.092258Z","steps":["trace[1292598655] 'process raft request' (duration: 307.957497ms)","trace[1292598655] 'compare' (duration: 644.553812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:46:02.092369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:01.139293Z","time spent":"953.024654ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:46:03.123471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822624380059255,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:46:03.616075Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.038483001s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:46:03.617646Z","caller":"traceutil/trace.go:171","msg":"trace[732936111] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"995.030729ms","start":"2026-05-06T00:46:02.6225Z","end":"2026-05-06T00:46:03.617531Z","steps":["trace[732936111] 'read index received' (duration: 994.038898ms)","trace[732936111] 'applied index is now lower than readState.Index' (duration: 990.721µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:46:03.617676Z","caller":"traceutil/trace.go:171","msg":"trace[522414725] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.049025474s","start":"2026-05-06T00:46:02.568596Z","end":"2026-05-06T00:46:03.617622Z","steps":["trace[522414725] 'process raft request' (duration: 1.048214035s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:03.617934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:02.568554Z","time spent":"1.049248346s","remote":"127.0.0.1:40500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:46:03.618016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.329332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:46:03.618086Z","caller":"traceutil/trace.go:171","msg":"trace[226841696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"995.586715ms","start":"2026-05-06T00:46:02.622467Z","end":"2026-05-06T00:46:03.618054Z","steps":["trace[226841696] 'agreement among raft nodes before linearized reading' (duration: 995.18986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:03.618119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.987373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-twhlr\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-06T00:46:03.618164Z","caller":"traceutil/trace.go:171","msg":"trace[1108056872] range","detail":"{range_begin:/registry/pods/kube-system/cilium-twhlr; range_end:; response_count:1; response_revision:659; }","duration":"558.052133ms","start":"2026-05-06T00:46:03.060098Z","end":"2026-05-06T00:46:03.618151Z","steps":["trace[1108056872] 'agreement among raft nodes before linearized reading' (duration: 557.945752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:03.618199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:03.060082Z","time spent":"558.109484ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-twhlr\" "} {"level":"warn","ts":"2026-05-06T00:46:03.618217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.480719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:46:03.618262Z","caller":"traceutil/trace.go:171","msg":"trace[737144228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:659; }","duration":"618.534119ms","start":"2026-05-06T00:46:02.999715Z","end":"2026-05-06T00:46:03.618249Z","steps":["trace[737144228] 'agreement among raft nodes before linearized reading' (duration: 618.428038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:03.618125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:02.622452Z","time spent":"995.664226ms","remote":"127.0.0.1:40456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:46:03.618284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:02.999708Z","time spent":"618.570161ms","remote":"127.0.0.1:40650","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-06T00:46:03.618014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.524124ms","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-06T00:46:03.61842Z","caller":"traceutil/trace.go:171","msg":"trace[2146084558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:659; }","duration":"620.973998ms","start":"2026-05-06T00:46:02.997434Z","end":"2026-05-06T00:46:03.618408Z","steps":["trace[2146084558] 'agreement among raft nodes before linearized reading' (duration: 620.401501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:03.618458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:02.997416Z","time spent":"621.030248ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T00:46:03.6181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.144937ms","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-06T00:46:03.621374Z","caller":"traceutil/trace.go:171","msg":"trace[464749684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"556.453314ms","start":"2026-05-06T00:46:03.064872Z","end":"2026-05-06T00:46:03.621325Z","steps":["trace[464749684] 'agreement among raft nodes before linearized reading' (duration: 553.059756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:03.621471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:03.06486Z","time spent":"556.564636ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T00:46:04.66641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.835877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:46:04.666512Z","caller":"traceutil/trace.go:171","msg":"trace[1279599172] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.041725518s","start":"2026-05-06T00:46:03.624767Z","end":"2026-05-06T00:46:04.666492Z","steps":["trace[1279599172] 'process raft request' (duration: 259.747ms)","trace[1279599172] 'compare' (duration: 781.363811ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:46:04.666585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:03.624736Z","time spent":"1.041821899s","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:46:04.679963Z","caller":"traceutil/trace.go:171","msg":"trace[1126359236] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.054771718s","start":"2026-05-06T00:46:03.625166Z","end":"2026-05-06T00:46:04.679937Z","steps":["trace[1126359236] 'process raft request' (duration: 1.054595915s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:04.680114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:03.625154Z","time spent":"1.054881529s","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:46:04.680422Z","caller":"traceutil/trace.go:171","msg":"trace[532714916] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.051174776s","start":"2026-05-06T00:46:03.629236Z","end":"2026-05-06T00:46:04.68041Z","steps":["trace[532714916] 'process raft request' (duration: 1.050648099s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:04.680524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:03.629225Z","time spent":"1.051233386s","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:46:04.680789Z","caller":"traceutil/trace.go:171","msg":"trace[523538540] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.039772294s","start":"2026-05-06T00:46:03.640991Z","end":"2026-05-06T00:46:04.680763Z","steps":["trace[523538540] 'process raft request' (duration: 1.039322829s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:04.680898Z","caller":"traceutil/trace.go:171","msg":"trace[1171354682] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:687; }","duration":"1.051870914s","start":"2026-05-06T00:46:03.629013Z","end":"2026-05-06T00:46:04.680884Z","steps":["trace[1171354682] 'read index received' (duration: 255.584152ms)","trace[1171354682] 'applied index is now lower than readState.Index' (duration: 796.285692ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:46:04.680922Z","caller":"traceutil/trace.go:171","msg":"trace[390783607] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.003118743s","start":"2026-05-06T00:46:03.677778Z","end":"2026-05-06T00:46:04.680897Z","steps":["trace[390783607] 'process raft request' (duration: 1.002815739s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:04.680946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:03.64097Z","time spent":"1.039889166s","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:46:04.681017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:03.677754Z","time spent":"1.003218684s","remote":"127.0.0.1:40758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:46:04.681026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052006986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:46:04.681061Z","caller":"traceutil/trace.go:171","msg":"trace[807879288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"1.052073486s","start":"2026-05-06T00:46:03.628976Z","end":"2026-05-06T00:46:04.68105Z","steps":["trace[807879288] 'agreement among raft nodes before linearized reading' (duration: 1.051994715s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:04.681089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:03.628958Z","time spent":"1.052125487s","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:46:04.681154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.130278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:46:04.681346Z","caller":"traceutil/trace.go:171","msg":"trace[2033015486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"461.266869ms","start":"2026-05-06T00:46:04.219961Z","end":"2026-05-06T00:46:04.681228Z","steps":["trace[2033015486] 'agreement among raft nodes before linearized reading' (duration: 461.043537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:04.681406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:04.219926Z","time spent":"461.466753ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:46:06.615186Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.618948882s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T00:46:07.667908Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.051615518s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T00:46:07.671457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055932785s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:46:07.672079Z","caller":"traceutil/trace.go:171","msg":"trace[1721551815] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"2.675903349s","start":"2026-05-06T00:46:04.996152Z","end":"2026-05-06T00:46:07.672056Z","steps":["trace[1721551815] 'process raft request' (duration: 1.619287096s)","trace[1721551815] 'compare' (duration: 1.055058355s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:46:07.672155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:04.996133Z","time spent":"2.675985961s","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:46:07.673547Z","caller":"traceutil/trace.go:171","msg":"trace[322169480] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"2.564085285s","start":"2026-05-06T00:46:05.109449Z","end":"2026-05-06T00:46:07.673535Z","steps":["trace[322169480] 'process raft request' (duration: 2.563125234s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:07.673641Z","caller":"traceutil/trace.go:171","msg":"trace[238603768] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"1.122415579s","start":"2026-05-06T00:46:06.551212Z","end":"2026-05-06T00:46:07.673628Z","steps":["trace[238603768] 'read index received' (duration: 64.164587ms)","trace[238603768] 'applied index is now lower than readState.Index' (duration: 1.058248922s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:46:07.673729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:05.10942Z","time spent":"2.564159687s","remote":"127.0.0.1:40500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:46:07.77823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.720289ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-06T00:46:07.778352Z","caller":"traceutil/trace.go:171","msg":"trace[520237023] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"104.662568ms","start":"2026-05-06T00:46:07.673666Z","end":"2026-05-06T00:46:07.778329Z","steps":["trace[520237023] 'read index received' (duration: 32.84µs)","trace[520237023] 'applied index is now lower than readState.Index' (duration: 104.627948ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:46:07.778446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15321246s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:46:07.778492Z","caller":"traceutil/trace.go:171","msg":"trace[1536154049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"1.153261s","start":"2026-05-06T00:46:06.625218Z","end":"2026-05-06T00:46:07.778479Z","steps":["trace[1536154049] 'agreement among raft nodes before linearized reading' (duration: 1.15318396s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:07.778507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083157669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:46:07.778537Z","caller":"traceutil/trace.go:171","msg":"trace[145573131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"1.083207469s","start":"2026-05-06T00:46:06.695322Z","end":"2026-05-06T00:46:07.778529Z","steps":["trace[145573131] 'agreement among raft nodes before linearized reading' (duration: 1.083147617s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:07.778543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:06.625164Z","time spent":"1.153369643s","remote":"127.0.0.1:40456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:46:07.778557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:06.695305Z","time spent":"1.08324759s","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:46:07.778447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093319872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:46:07.778704Z","caller":"traceutil/trace.go:171","msg":"trace[1876826582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:667; }","duration":"1.093583346s","start":"2026-05-06T00:46:06.685062Z","end":"2026-05-06T00:46:07.778645Z","steps":["trace[1876826582] 'agreement among raft nodes before linearized reading' (duration: 1.093270762s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:07.778855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:06.685039Z","time spent":"1.093716457s","remote":"127.0.0.1:40650","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-06T00:46:07.778323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227068187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:46:07.779039Z","caller":"traceutil/trace.go:171","msg":"trace[1297556219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"1.227851476s","start":"2026-05-06T00:46:06.551164Z","end":"2026-05-06T00:46:07.779015Z","steps":["trace[1297556219] 'agreement among raft nodes before linearized reading' (duration: 1.12252362s)","trace[1297556219] 'range keys from in-memory index tree' (duration: 104.542497ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:46:07.778966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095424027s","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-06T00:46:07.780385Z","caller":"traceutil/trace.go:171","msg":"trace[518963576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:667; }","duration":"1.096900904s","start":"2026-05-06T00:46:06.683463Z","end":"2026-05-06T00:46:07.780364Z","steps":["trace[518963576] 'agreement among raft nodes before linearized reading' (duration: 1.094982542s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:07.780436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:06.683446Z","time spent":"1.096975674s","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T00:46:07.779001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108364463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:46:07.780598Z","caller":"traceutil/trace.go:171","msg":"trace[531220159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"1.110056792s","start":"2026-05-06T00:46:06.670504Z","end":"2026-05-06T00:46:07.780561Z","steps":["trace[531220159] 'agreement among raft nodes before linearized reading' (duration: 1.108139761s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:07.780675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:06.670478Z","time spent":"1.110177334s","remote":"127.0.0.1:40650","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-06T00:46:07.779099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:06.551136Z","time spent":"1.227947986s","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:46:17.682272Z","caller":"traceutil/trace.go:171","msg":"trace[2132251399] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"142.981809ms","start":"2026-05-06T00:46:17.539265Z","end":"2026-05-06T00:46:17.682247Z","steps":["trace[2132251399] 'process raft request' (duration: 142.774837ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:23.340793Z","caller":"traceutil/trace.go:171","msg":"trace[1403270215] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"104.325608ms","start":"2026-05-06T00:46:23.236439Z","end":"2026-05-06T00:46:23.340765Z","steps":["trace[1403270215] 'process raft request' (duration: 104.185862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:23.590518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.355559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:46:23.590637Z","caller":"traceutil/trace.go:171","msg":"trace[1091385645] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"176.933724ms","start":"2026-05-06T00:46:23.413676Z","end":"2026-05-06T00:46:23.59061Z","steps":["trace[1091385645] 'process raft request' (duration: 29.408287ms)","trace[1091385645] 'compare' (duration: 147.260946ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:46:25.026976Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822624380059686,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:46:25.208228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459817863s","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-06T00:46:25.20831Z","caller":"traceutil/trace.go:171","msg":"trace[1441889869] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:834; }","duration":"1.459942377s","start":"2026-05-06T00:46:23.748351Z","end":"2026-05-06T00:46:25.208293Z","steps":["trace[1441889869] 'range keys from in-memory index tree' (duration: 1.459636155s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:25.208398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:23.748332Z","time spent":"1.460055642s","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T00:46:25.208449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293645305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-06T00:46:25.208526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30371252s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T00:46:25.208583Z","caller":"traceutil/trace.go:171","msg":"trace[984282244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:834; }","duration":"1.293854144s","start":"2026-05-06T00:46:23.914706Z","end":"2026-05-06T00:46:25.20856Z","steps":["trace[984282244] 'range keys from in-memory index tree' (duration: 1.293427105s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:25.208645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304826417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-06T00:46:25.208643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:23.914685Z","time spent":"1.293945947s","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T00:46:25.208678Z","caller":"traceutil/trace.go:171","msg":"trace[382025598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:834; }","duration":"1.30489979s","start":"2026-05-06T00:46:23.903761Z","end":"2026-05-06T00:46:25.20866Z","steps":["trace[382025598] 'range keys from in-memory index tree' (duration: 1.304629489s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:25.20859Z","caller":"traceutil/trace.go:171","msg":"trace[1528997222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:834; }","duration":"1.303854927s","start":"2026-05-06T00:46:23.904716Z","end":"2026-05-06T00:46:25.208571Z","steps":["trace[1528997222] 'range keys from in-memory index tree' (duration: 1.303561774s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:25.208706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:23.903732Z","time spent":"1.304963592s","remote":"127.0.0.1:40650","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-06T00:46:25.208686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299505466s","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-06T00:46:25.208713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:23.904689Z","time spent":"1.304012203s","remote":"127.0.0.1:40650","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-06T00:46:25.20877Z","caller":"traceutil/trace.go:171","msg":"trace[313927039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:834; }","duration":"1.299634282s","start":"2026-05-06T00:46:23.909115Z","end":"2026-05-06T00:46:25.208749Z","steps":["trace[313927039] 'range keys from in-memory index tree' (duration: 1.299221844s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:25.208812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:23.909096Z","time spent":"1.299706465s","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T00:46:25.31647Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.112774111s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:46:25.316942Z","caller":"traceutil/trace.go:171","msg":"trace[467500336] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:869; }","duration":"790.535205ms","start":"2026-05-06T00:46:24.526387Z","end":"2026-05-06T00:46:25.316922Z","steps":["trace[467500336] 'read index received' (duration: 790.403429ms)","trace[467500336] 'applied index is now lower than readState.Index' (duration: 131.106µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:46:25.317255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.849818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:46:25.317313Z","caller":"traceutil/trace.go:171","msg":"trace[538089838] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:835; }","duration":"790.934011ms","start":"2026-05-06T00:46:24.526342Z","end":"2026-05-06T00:46:25.317276Z","steps":["trace[538089838] 'agreement among raft nodes before linearized reading' (duration: 790.865829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:25.317342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:24.526321Z","time spent":"791.012684ms","remote":"127.0.0.1:40518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:46:25.317415Z","caller":"traceutil/trace.go:171","msg":"trace[576177590] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"1.113622665s","start":"2026-05-06T00:46:24.203571Z","end":"2026-05-06T00:46:25.317194Z","steps":["trace[576177590] 'process raft request' (duration: 1.113185337s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:25.317638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:24.203548Z","time spent":"1.113966089s","remote":"127.0.0.1:40632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:46:25.358549Z","caller":"traceutil/trace.go:171","msg":"trace[397759565] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"145.85788ms","start":"2026-05-06T00:46:25.212665Z","end":"2026-05-06T00:46:25.358523Z","steps":["trace[397759565] 'process raft request' (duration: 145.693242ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:25.358935Z","caller":"traceutil/trace.go:171","msg":"trace[1261524128] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"145.735105ms","start":"2026-05-06T00:46:25.213168Z","end":"2026-05-06T00:46:25.358903Z","steps":["trace[1261524128] 'process raft request' (duration: 145.316238ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:25.359026Z","caller":"traceutil/trace.go:171","msg":"trace[1654877544] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"145.61974ms","start":"2026-05-06T00:46:25.213391Z","end":"2026-05-06T00:46:25.359011Z","steps":["trace[1654877544] 'process raft request' (duration: 145.37381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:25.359107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.103372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:46:25.359068Z","caller":"traceutil/trace.go:171","msg":"trace[1502426669] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"145.234793ms","start":"2026-05-06T00:46:25.213815Z","end":"2026-05-06T00:46:25.35905Z","steps":["trace[1502426669] 'process raft request' (duration: 145.094538ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:25.359187Z","caller":"traceutil/trace.go:171","msg":"trace[590658396] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:840; }","duration":"761.237927ms","start":"2026-05-06T00:46:24.59793Z","end":"2026-05-06T00:46:25.359168Z","steps":["trace[590658396] 'agreement among raft nodes before linearized reading' (duration: 761.0862ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:25.359165Z","caller":"traceutil/trace.go:171","msg":"trace[1856755042] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"145.184652ms","start":"2026-05-06T00:46:25.213938Z","end":"2026-05-06T00:46:25.359123Z","steps":["trace[1856755042] 'process raft request' (duration: 145.023385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:25.359232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:24.597909Z","time spent":"761.31362ms","remote":"127.0.0.1:40826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:46:30.533595Z","caller":"traceutil/trace.go:171","msg":"trace[1687697305] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"290.242804ms","start":"2026-05-06T00:46:30.24332Z","end":"2026-05-06T00:46:30.533563Z","steps":["trace[1687697305] 'read index received' (duration: 228.434218ms)","trace[1687697305] 'applied index is now lower than readState.Index' (duration: 61.806895ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:46:30.533639Z","caller":"traceutil/trace.go:171","msg":"trace[18976733] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"324.951339ms","start":"2026-05-06T00:46:30.208665Z","end":"2026-05-06T00:46:30.533617Z","steps":["trace[18976733] 'process raft request' (duration: 263.154923ms)","trace[18976733] 'compare' (duration: 61.583867ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:46:30.533712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.378238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:46:30.53374Z","caller":"traceutil/trace.go:171","msg":"trace[1109168196] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:0; response_revision:892; }","duration":"290.441591ms","start":"2026-05-06T00:46:30.243289Z","end":"2026-05-06T00:46:30.533731Z","steps":["trace[1109168196] 'agreement among raft nodes before linearized reading' (duration: 290.389669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:30.533821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:30.208647Z","time spent":"325.069922ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5661,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:46:30.700858Z","caller":"traceutil/trace.go:171","msg":"trace[2039693246] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"162.811526ms","start":"2026-05-06T00:46:30.538011Z","end":"2026-05-06T00:46:30.700823Z","steps":["trace[2039693246] 'process raft request' (duration: 159.361954ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:30.701186Z","caller":"traceutil/trace.go:171","msg":"trace[1538186051] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"161.604021ms","start":"2026-05-06T00:46:30.539568Z","end":"2026-05-06T00:46:30.701172Z","steps":["trace[1538186051] 'process raft request' (duration: 161.434505ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:30.701218Z","caller":"traceutil/trace.go:171","msg":"trace[1564398802] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"162.096109ms","start":"2026-05-06T00:46:30.539088Z","end":"2026-05-06T00:46:30.701184Z","steps":["trace[1564398802] 'process raft request' (duration: 161.644212ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:30.871119Z","caller":"traceutil/trace.go:171","msg":"trace[1893822999] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"161.370182ms","start":"2026-05-06T00:46:30.709727Z","end":"2026-05-06T00:46:30.871097Z","steps":["trace[1893822999] 'process raft request' (duration: 161.036359ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:30.871109Z","caller":"traceutil/trace.go:171","msg":"trace[176925098] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:931; }","duration":"156.191162ms","start":"2026-05-06T00:46:30.714891Z","end":"2026-05-06T00:46:30.871082Z","steps":["trace[176925098] 'read index received' (duration: 47.248905ms)","trace[176925098] 'applied index is now lower than readState.Index' (duration: 108.940937ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:46:30.871701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.732362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-05-06T00:46:30.871763Z","caller":"traceutil/trace.go:171","msg":"trace[997549307] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:897; }","duration":"156.926801ms","start":"2026-05-06T00:46:30.714823Z","end":"2026-05-06T00:46:30.87175Z","steps":["trace[997549307] 'agreement among raft nodes before linearized reading' (duration: 156.336428ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:31.536439Z","caller":"traceutil/trace.go:171","msg":"trace[1483987170] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:936; }","duration":"116.817613ms","start":"2026-05-06T00:46:31.419601Z","end":"2026-05-06T00:46:31.536418Z","steps":["trace[1483987170] 'read index received' (duration: 111.574193ms)","trace[1483987170] 'applied index is now lower than readState.Index' (duration: 5.2427ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:46:31.536616Z","caller":"traceutil/trace.go:171","msg":"trace[1955521262] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"147.856602ms","start":"2026-05-06T00:46:31.388743Z","end":"2026-05-06T00:46:31.536599Z","steps":["trace[1955521262] 'process raft request' (duration: 142.338122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:31.536632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.219341ms","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-06T00:46:31.536816Z","caller":"traceutil/trace.go:171","msg":"trace[1397930554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"151.43305ms","start":"2026-05-06T00:46:31.385372Z","end":"2026-05-06T00:46:31.536805Z","steps":["trace[1397930554] 'agreement among raft nodes before linearized reading' (duration: 151.147158ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:31.536796Z","caller":"traceutil/trace.go:171","msg":"trace[523995404] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"147.436556ms","start":"2026-05-06T00:46:31.389326Z","end":"2026-05-06T00:46:31.536762Z","steps":["trace[523995404] 'process raft request' (duration: 147.060572ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:31.537514Z","caller":"traceutil/trace.go:171","msg":"trace[2134611316] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"111.521562ms","start":"2026-05-06T00:46:31.425288Z","end":"2026-05-06T00:46:31.536809Z","steps":["trace[2134611316] 'process raft request' (duration: 111.378206ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:32.986529Z","caller":"traceutil/trace.go:171","msg":"trace[698808806] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"228.381346ms","start":"2026-05-06T00:46:32.758123Z","end":"2026-05-06T00:46:32.986505Z","steps":["trace[698808806] 'process raft request' (duration: 216.924155ms)","trace[698808806] 'compare' (duration: 11.16967ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:46:32.986578Z","caller":"traceutil/trace.go:171","msg":"trace[426289513] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"226.827628ms","start":"2026-05-06T00:46:32.759722Z","end":"2026-05-06T00:46:32.986549Z","steps":["trace[426289513] 'process raft request' (duration: 226.664302ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:32.986693Z","caller":"traceutil/trace.go:171","msg":"trace[2002788415] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:944; }","duration":"167.972261ms","start":"2026-05-06T00:46:32.818681Z","end":"2026-05-06T00:46:32.986654Z","steps":["trace[2002788415] 'read index received' (duration: 156.378535ms)","trace[2002788415] 'applied index is now lower than readState.Index' (duration: 11.592456ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:46:32.9869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.20113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-rvxbd\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-05-06T00:46:32.986939Z","caller":"traceutil/trace.go:171","msg":"trace[1798762164] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"220.213039ms","start":"2026-05-06T00:46:32.76671Z","end":"2026-05-06T00:46:32.986923Z","steps":["trace[1798762164] 'process raft request' (duration: 219.774922ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:32.986943Z","caller":"traceutil/trace.go:171","msg":"trace[1574555841] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-rvxbd; range_end:; response_count:1; response_revision:910; }","duration":"168.299785ms","start":"2026-05-06T00:46:32.81863Z","end":"2026-05-06T00:46:32.98693Z","steps":["trace[1574555841] 'agreement among raft nodes before linearized reading' (duration: 168.137839ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:32.987185Z","caller":"traceutil/trace.go:171","msg":"trace[1140786742] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"166.664893ms","start":"2026-05-06T00:46:32.820426Z","end":"2026-05-06T00:46:32.987091Z","steps":["trace[1140786742] 'process raft request' (duration: 166.59067ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:47.822019Z","caller":"traceutil/trace.go:171","msg":"trace[695970406] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"131.966113ms","start":"2026-05-06T00:46:47.690029Z","end":"2026-05-06T00:46:47.821995Z","steps":["trace[695970406] 'process raft request' (duration: 131.815908ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:47:15.961499Z","caller":"traceutil/trace.go:171","msg":"trace[1971003231] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"106.949648ms","start":"2026-05-06T00:47:15.854529Z","end":"2026-05-06T00:47:15.961479Z","steps":["trace[1971003231] 'process raft request' (duration: 106.828655ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:47:46.458619Z","caller":"traceutil/trace.go:171","msg":"trace[335543175] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"101.421946ms","start":"2026-05-06T00:47:46.35717Z","end":"2026-05-06T00:47:46.458592Z","steps":["trace[335543175] 'process raft request' (duration: 101.298734ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:02.499348Z","caller":"traceutil/trace.go:171","msg":"trace[1399817456] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"165.847723ms","start":"2026-05-06T00:48:02.333481Z","end":"2026-05-06T00:48:02.499329Z","steps":["trace[1399817456] 'process raft request' (duration: 165.732381ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:20.826123Z","caller":"traceutil/trace.go:171","msg":"trace[5746540] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"119.245674ms","start":"2026-05-06T00:48:20.706834Z","end":"2026-05-06T00:48:20.826079Z","steps":["trace[5746540] 'process raft request' (duration: 112.071519ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:21.546431Z","caller":"traceutil/trace.go:171","msg":"trace[1313419543] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"389.248818ms","start":"2026-05-06T00:48:21.157159Z","end":"2026-05-06T00:48:21.546408Z","steps":["trace[1313419543] 'process raft request' (duration: 305.412388ms)","trace[1313419543] 'compare' (duration: 83.497995ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:21.546538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:21.157133Z","time spent":"389.357861ms","remote":"127.0.0.1:40492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:48:21.546704Z","caller":"traceutil/trace.go:171","msg":"trace[596721649] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"257.495814ms","start":"2026-05-06T00:48:21.289198Z","end":"2026-05-06T00:48:21.546694Z","steps":["trace[596721649] 'read index received' (duration: 173.308107ms)","trace[596721649] 'applied index is now lower than readState.Index' (duration: 84.186677ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:21.546795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.597805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:21.54683Z","caller":"traceutil/trace.go:171","msg":"trace[122210560] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1386; }","duration":"257.671577ms","start":"2026-05-06T00:48:21.289149Z","end":"2026-05-06T00:48:21.54682Z","steps":["trace[122210560] 'agreement among raft nodes before linearized reading' (duration: 257.586586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:22.88414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.269554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:48:22.884226Z","caller":"traceutil/trace.go:171","msg":"trace[136405325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1389; }","duration":"204.408095ms","start":"2026-05-06T00:48:22.679798Z","end":"2026-05-06T00:48:22.884206Z","steps":["trace[136405325] 'range keys from in-memory index tree' (duration: 204.09708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:22.884807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.030472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:48:22.884886Z","caller":"traceutil/trace.go:171","msg":"trace[1400224203] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1389; }","duration":"278.162995ms","start":"2026-05-06T00:48:22.606704Z","end":"2026-05-06T00:48:22.884867Z","steps":["trace[1400224203] 'count revisions from in-memory index tree' (duration: 277.926981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:22.885178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.744468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:22.885797Z","caller":"traceutil/trace.go:171","msg":"trace[1144415396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1389; }","duration":"243.931882ms","start":"2026-05-06T00:48:22.641382Z","end":"2026-05-06T00:48:22.885314Z","steps":["trace[1144415396] 'range keys from in-memory index tree' (duration: 243.670007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:23.39011Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822624380061008,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:48:23.661469Z","caller":"traceutil/trace.go:171","msg":"trace[519831686] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"772.704708ms","start":"2026-05-06T00:48:22.888743Z","end":"2026-05-06T00:48:23.661448Z","steps":["trace[519831686] 'process raft request' (duration: 772.597666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:23.661675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:22.888717Z","time spent":"772.894912ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:23.833507Z","caller":"traceutil/trace.go:171","msg":"trace[1225173917] linearizableReadLoop","detail":"{readStateIndex:1453; appliedIndex:1451; }","duration":"944.000907ms","start":"2026-05-06T00:48:22.889483Z","end":"2026-05-06T00:48:23.833484Z","steps":["trace[1225173917] 'read index received' (duration: 772.043685ms)","trace[1225173917] 'applied index is now lower than readState.Index' (duration: 171.956222ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:23.833546Z","caller":"traceutil/trace.go:171","msg":"trace[1950270262] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"943.5587ms","start":"2026-05-06T00:48:22.889962Z","end":"2026-05-06T00:48:23.83352Z","steps":["trace[1950270262] 'process raft request' (duration: 934.85803ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:23.833589Z","caller":"traceutil/trace.go:171","msg":"trace[2002263940] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"940.955385ms","start":"2026-05-06T00:48:22.892607Z","end":"2026-05-06T00:48:23.833563Z","steps":["trace[2002263940] 'process raft request' (duration: 940.798502ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:23.833639Z","caller":"traceutil/trace.go:171","msg":"trace[2106072793] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"277.267181ms","start":"2026-05-06T00:48:23.556361Z","end":"2026-05-06T00:48:23.833628Z","steps":["trace[2106072793] 'process raft request' (duration: 277.084948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:23.833719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:22.892588Z","time spent":"941.058236ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:48:23.833923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:22.889952Z","time spent":"943.696312ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:48:23.834038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.565397ms","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-06T00:48:23.834072Z","caller":"traceutil/trace.go:171","msg":"trace[1459032620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1393; }","duration":"944.633109ms","start":"2026-05-06T00:48:22.889429Z","end":"2026-05-06T00:48:23.834062Z","steps":["trace[1459032620] 'agreement among raft nodes before linearized reading' (duration: 944.540007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:23.834096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:22.889412Z","time spent":"944.67944ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T00:48:23.834173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.983438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:23.834196Z","caller":"traceutil/trace.go:171","msg":"trace[1655557343] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1393; }","duration":"177.041439ms","start":"2026-05-06T00:48:23.657147Z","end":"2026-05-06T00:48:23.834189Z","steps":["trace[1655557343] 'agreement among raft nodes before linearized reading' (duration: 177.001238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:23.834314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.007539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:48:23.834336Z","caller":"traceutil/trace.go:171","msg":"trace[1805920097] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1393; }","duration":"173.0461ms","start":"2026-05-06T00:48:23.661285Z","end":"2026-05-06T00:48:23.834331Z","steps":["trace[1805920097] 'agreement among raft nodes before linearized reading' (duration: 173.01198ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:24.810234Z","caller":"traceutil/trace.go:171","msg":"trace[567113199] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"341.793872ms","start":"2026-05-06T00:48:24.468413Z","end":"2026-05-06T00:48:24.810207Z","steps":["trace[567113199] 'process raft request' (duration: 341.618439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:24.810399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:24.468374Z","time spent":"341.928954ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:24.810783Z","caller":"traceutil/trace.go:171","msg":"trace[2135367644] linearizableReadLoop","detail":"{readStateIndex:1456; appliedIndex:1455; }","duration":"186.592672ms","start":"2026-05-06T00:48:24.624148Z","end":"2026-05-06T00:48:24.81074Z","steps":["trace[2135367644] 'read index received' (duration: 185.797979ms)","trace[2135367644] 'applied index is now lower than readState.Index' (duration: 787.113µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:24.810967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.784705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:24.811087Z","caller":"traceutil/trace.go:171","msg":"trace[1286313735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1395; }","duration":"186.952877ms","start":"2026-05-06T00:48:24.624103Z","end":"2026-05-06T00:48:24.811056Z","steps":["trace[1286313735] 'agreement among raft nodes before linearized reading' (duration: 186.740794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:25.508952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.251172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:25.509018Z","caller":"traceutil/trace.go:171","msg":"trace[271052871] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1395; }","duration":"323.374724ms","start":"2026-05-06T00:48:25.185631Z","end":"2026-05-06T00:48:25.509006Z","steps":["trace[271052871] 'count revisions from in-memory index tree' (duration: 323.14005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:25.509047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:25.185611Z","time spent":"323.430225ms","remote":"127.0.0.1:59880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:48:26.250379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822624380061022,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:48:26.332373Z","caller":"traceutil/trace.go:171","msg":"trace[1926275918] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"659.474506ms","start":"2026-05-06T00:48:25.672873Z","end":"2026-05-06T00:48:26.332348Z","steps":["trace[1926275918] 'process raft request' (duration: 659.304883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:26.332771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:25.67285Z","time spent":"659.825992ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:26.452711Z","caller":"traceutil/trace.go:171","msg":"trace[408130207] linearizableReadLoop","detail":"{readStateIndex:1458; appliedIndex:1457; }","duration":"702.718229ms","start":"2026-05-06T00:48:25.749968Z","end":"2026-05-06T00:48:26.452686Z","steps":["trace[408130207] 'read index received' (duration: 582.402804ms)","trace[408130207] 'applied index is now lower than readState.Index' (duration: 120.314455ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:26.452852Z","caller":"traceutil/trace.go:171","msg":"trace[289240339] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"613.667483ms","start":"2026-05-06T00:48:25.839172Z","end":"2026-05-06T00:48:26.452839Z","steps":["trace[289240339] 'process raft request' (duration: 601.617407ms)","trace[289240339] 'compare' (duration: 11.793752ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:26.452966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:25.83915Z","time spent":"613.741334ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:48:26.453132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.166438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:48:26.453152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.007598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-06T00:48:26.453237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.305285ms","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-06T00:48:26.45326Z","caller":"traceutil/trace.go:171","msg":"trace[1890280573] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1397; }","duration":"189.201021ms","start":"2026-05-06T00:48:26.264038Z","end":"2026-05-06T00:48:26.453239Z","steps":["trace[1890280573] 'agreement among raft nodes before linearized reading' (duration: 188.957837ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:26.453299Z","caller":"traceutil/trace.go:171","msg":"trace[1456801127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1397; }","duration":"614.404746ms","start":"2026-05-06T00:48:25.838879Z","end":"2026-05-06T00:48:26.453283Z","steps":["trace[1456801127] 'agreement among raft nodes before linearized reading' (duration: 614.215803ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:26.453182Z","caller":"traceutil/trace.go:171","msg":"trace[120540837] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1397; }","duration":"703.250489ms","start":"2026-05-06T00:48:25.749917Z","end":"2026-05-06T00:48:26.453168Z","steps":["trace[120540837] 'agreement among raft nodes before linearized reading' (duration: 703.182468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:26.453371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:25.838858Z","time spent":"614.501657ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T00:48:26.453409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:25.749899Z","time spent":"703.496682ms","remote":"127.0.0.1:40498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:48:26.453056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.878765ms","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-06T00:48:26.453495Z","caller":"traceutil/trace.go:171","msg":"trace[1630226123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1397; }","duration":"615.357292ms","start":"2026-05-06T00:48:25.838123Z","end":"2026-05-06T00:48:26.45348Z","steps":["trace[1630226123] 'agreement among raft nodes before linearized reading' (duration: 614.813143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:26.453538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:25.838101Z","time spent":"615.425112ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T00:48:26.4538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.631043ms","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-06T00:48:26.453841Z","caller":"traceutil/trace.go:171","msg":"trace[1945095026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1397; }","duration":"599.706644ms","start":"2026-05-06T00:48:25.854116Z","end":"2026-05-06T00:48:26.453823Z","steps":["trace[1945095026] 'agreement among raft nodes before linearized reading' (duration: 599.611793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:26.45386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:25.854096Z","time spent":"599.759345ms","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T00:48:26.604211Z","caller":"traceutil/trace.go:171","msg":"trace[812877627] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"145.041378ms","start":"2026-05-06T00:48:26.459137Z","end":"2026-05-06T00:48:26.604179Z","steps":["trace[812877627] 'process raft request' (duration: 104.945193ms)","trace[812877627] 'compare' (duration: 39.959962ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:27.034439Z","caller":"traceutil/trace.go:171","msg":"trace[167777783] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1402; }","duration":"112.49696ms","start":"2026-05-06T00:48:26.921909Z","end":"2026-05-06T00:48:27.034406Z","steps":["trace[167777783] 'process raft request' (duration: 81.136476ms)","trace[167777783] 'compare' (duration: 31.10632ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:42.561696Z","caller":"traceutil/trace.go:171","msg":"trace[1666027087] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"161.904947ms","start":"2026-05-06T00:48:42.399763Z","end":"2026-05-06T00:48:42.561668Z","steps":["trace[1666027087] 'process raft request' (duration: 161.714924ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:42.809498Z","caller":"traceutil/trace.go:171","msg":"trace[576431666] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"119.543093ms","start":"2026-05-06T00:48:42.689934Z","end":"2026-05-06T00:48:42.809477Z","steps":["trace[576431666] 'process raft request' (duration: 119.474972ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:42.80955Z","caller":"traceutil/trace.go:171","msg":"trace[992948934] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"119.879167ms","start":"2026-05-06T00:48:42.689619Z","end":"2026-05-06T00:48:42.809498Z","steps":["trace[992948934] 'process raft request' (duration: 116.762498ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:50.932744Z","caller":"traceutil/trace.go:171","msg":"trace[2010491605] linearizableReadLoop","detail":"{readStateIndex:1548; appliedIndex:1547; }","duration":"211.116413ms","start":"2026-05-06T00:48:50.721595Z","end":"2026-05-06T00:48:50.932711Z","steps":["trace[2010491605] 'read index received' (duration: 210.812119ms)","trace[2010491605] 'applied index is now lower than readState.Index' (duration: 303.004µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:50.932814Z","caller":"traceutil/trace.go:171","msg":"trace[1831743614] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"212.644427ms","start":"2026-05-06T00:48:50.720127Z","end":"2026-05-06T00:48:50.932772Z","steps":["trace[1831743614] 'process raft request' (duration: 212.353833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:50.932996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.358998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:kubelet-config\" ","response":"range_response_count:1 size:345"} {"level":"info","ts":"2026-05-06T00:48:50.933091Z","caller":"traceutil/trace.go:171","msg":"trace[464375624] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:kubelet-config; range_end:; response_count:1; response_revision:1481; }","duration":"211.502699ms","start":"2026-05-06T00:48:50.721571Z","end":"2026-05-06T00:48:50.933074Z","steps":["trace[464375624] 'agreement among raft nodes before linearized reading' (duration: 211.325897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:50.933035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.311312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:kubeadm-certs\" ","response":"range_response_count:1 size:339"} {"level":"info","ts":"2026-05-06T00:48:50.933244Z","caller":"traceutil/trace.go:171","msg":"trace[51461311] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:kubeadm-certs; range_end:; response_count:1; response_revision:1481; }","duration":"198.542975ms","start":"2026-05-06T00:48:50.734651Z","end":"2026-05-06T00:48:50.933194Z","steps":["trace[51461311] 'agreement among raft nodes before linearized reading' (duration: 198.334682ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:53.055142Z","caller":"traceutil/trace.go:171","msg":"trace[120793010] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"106.307277ms","start":"2026-05-06T00:48:52.948804Z","end":"2026-05-06T00:48:53.055111Z","steps":["trace[120793010] 'process raft request' (duration: 44.781343ms)","trace[120793010] 'compare' (duration: 61.309951ms)"],"step_count":2}