{"level":"warn","ts":"2026-05-27T13:46:34.308958Z","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-27T13:46:34.309365Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.188: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.188:2380","--initial-cluster=instance=https://199.19.213.188:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.188:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.188: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-27T13:46:34.309524Z","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-27T13:46:34.309588Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.188:2380"]} {"level":"info","ts":"2026-05-27T13:46:34.309647Z","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-27T13:46:34.31164Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.188:2379"]} {"level":"info","ts":"2026-05-27T13:46:34.311818Z","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.188:2380"],"listen-peer-urls":["https://199.19.213.188:2380"],"advertise-client-urls":["https://199.19.213.188:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.188:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.188: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-27T13:46:34.330048Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"17.303092ms"} {"level":"info","ts":"2026-05-27T13:46:34.371722Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"201fe5d2d7e0161a","cluster-id":"92c0fc3a33fabd38"} {"level":"info","ts":"2026-05-27T13:46:34.371936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T13:46:34.371971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became follower at term 0"} {"level":"info","ts":"2026-05-27T13:46:34.371994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 201fe5d2d7e0161a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T13:46:34.372009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became follower at term 1"} {"level":"info","ts":"2026-05-27T13:46:34.372071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a switched to configuration voters=(2314821427219404314)"} {"level":"warn","ts":"2026-05-27T13:46:34.390759Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T13:46:34.425268Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T13:46:34.597947Z","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-27T13:46:34.619181Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"201fe5d2d7e0161a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T13:46:34.619532Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"201fe5d2d7e0161a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T13:46:34.6237Z","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-27T13:46:34.623838Z","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-27T13:46:34.623862Z","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-27T13:46:34.624519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a switched to configuration voters=(2314821427219404314)"} {"level":"info","ts":"2026-05-27T13:46:34.624758Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92c0fc3a33fabd38","local-member-id":"201fe5d2d7e0161a","added-peer-id":"201fe5d2d7e0161a","added-peer-peer-urls":["https://199.19.213.188:2380"]} {"level":"info","ts":"2026-05-27T13:46:34.627661Z","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-27T13:46:34.627761Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.188:2380"} {"level":"info","ts":"2026-05-27T13:46:34.627824Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.188:2380"} {"level":"info","ts":"2026-05-27T13:46:34.627966Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"201fe5d2d7e0161a","initial-advertise-peer-urls":["https://199.19.213.188:2380"],"listen-peer-urls":["https://199.19.213.188:2380"],"advertise-client-urls":["https://199.19.213.188:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.188:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T13:46:34.628031Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T13:46:35.373448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T13:46:35.373531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T13:46:35.373575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a received MsgPreVoteResp from 201fe5d2d7e0161a at term 1"} {"level":"info","ts":"2026-05-27T13:46:35.373597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became candidate at term 2"} {"level":"info","ts":"2026-05-27T13:46:35.373608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a received MsgVoteResp from 201fe5d2d7e0161a at term 2"} {"level":"info","ts":"2026-05-27T13:46:35.373626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became leader at term 2"} {"level":"info","ts":"2026-05-27T13:46:35.373646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 201fe5d2d7e0161a elected leader 201fe5d2d7e0161a at term 2"} {"level":"info","ts":"2026-05-27T13:46:35.374837Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T13:46:35.37767Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T13:46:35.377673Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"201fe5d2d7e0161a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.188:2379]}","request-path":"/0/members/201fe5d2d7e0161a/attributes","cluster-id":"92c0fc3a33fabd38","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T13:46:35.377732Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T13:46:35.378166Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T13:46:35.378197Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T13:46:35.380943Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T13:46:35.381028Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.188:2379"} {"level":"info","ts":"2026-05-27T13:46:35.385348Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92c0fc3a33fabd38","local-member-id":"201fe5d2d7e0161a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T13:46:35.385595Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T13:46:35.385673Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T13:46:36.955899Z","caller":"traceutil/trace.go:171","msg":"trace[1005067106] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"194.983151ms","start":"2026-05-27T13:46:36.76089Z","end":"2026-05-27T13:46:36.955873Z","steps":["trace[1005067106] 'read index received' (duration: 194.71914ms)","trace[1005067106] 'applied index is now lower than readState.Index' (duration: 262.831µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:36.955989Z","caller":"traceutil/trace.go:171","msg":"trace[1700463095] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"195.637656ms","start":"2026-05-27T13:46:36.760292Z","end":"2026-05-27T13:46:36.955929Z","steps":["trace[1700463095] 'process raft request' (duration: 195.380934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:36.956115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.190033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T13:46:36.956211Z","caller":"traceutil/trace.go:171","msg":"trace[1351720956] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"195.317883ms","start":"2026-05-27T13:46:36.760876Z","end":"2026-05-27T13:46:36.956193Z","steps":["trace[1351720956] 'agreement among raft nodes before linearized reading' (duration: 195.136402ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.381192Z","caller":"traceutil/trace.go:171","msg":"trace[1251600326] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"620.036197ms","start":"2026-05-27T13:46:36.761115Z","end":"2026-05-27T13:46:37.381151Z","steps":["trace[1251600326] 'process raft request' (duration: 550.479069ms)","trace[1251600326] 'compare' (duration: 69.197856ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:37.381595Z","caller":"traceutil/trace.go:171","msg":"trace[474507024] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"554.876189ms","start":"2026-05-27T13:46:36.826586Z","end":"2026-05-27T13:46:37.381462Z","steps":["trace[474507024] 'process raft request' (duration: 554.670618ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.381649Z","caller":"traceutil/trace.go:171","msg":"trace[550846625] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"425.60672ms","start":"2026-05-27T13:46:36.955982Z","end":"2026-05-27T13:46:37.381589Z","steps":["trace[550846625] 'read index received' (duration: 355.66316ms)","trace[550846625] 'applied index is now lower than readState.Index' (duration: 69.94134ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:37.381759Z","caller":"traceutil/trace.go:171","msg":"trace[1095028545] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"555.453803ms","start":"2026-05-27T13:46:36.82629Z","end":"2026-05-27T13:46:37.381743Z","steps":["trace[1095028545] 'process raft request' (duration: 554.760409ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.381803Z","caller":"traceutil/trace.go:171","msg":"trace[593042944] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"555.268222ms","start":"2026-05-27T13:46:36.826516Z","end":"2026-05-27T13:46:37.381785Z","steps":["trace[593042944] 'process raft request' (duration: 554.618587ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.381826Z","caller":"traceutil/trace.go:171","msg":"trace[1974009715] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"544.144409ms","start":"2026-05-27T13:46:36.837668Z","end":"2026-05-27T13:46:37.381813Z","steps":["trace[1974009715] 'process raft request' (duration: 543.771076ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.381785Z","caller":"traceutil/trace.go:171","msg":"trace[1993453213] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"555.06151ms","start":"2026-05-27T13:46:36.826661Z","end":"2026-05-27T13:46:37.381723Z","steps":["trace[1993453213] 'process raft request' (duration: 554.658907ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.38189Z","caller":"traceutil/trace.go:171","msg":"trace[132505539] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"555.138731ms","start":"2026-05-27T13:46:36.826745Z","end":"2026-05-27T13:46:37.381883Z","steps":["trace[132505539] 'process raft request' (duration: 554.618338ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.381858Z","caller":"traceutil/trace.go:171","msg":"trace[1752776262] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"554.98712ms","start":"2026-05-27T13:46:36.826863Z","end":"2026-05-27T13:46:37.38185Z","steps":["trace[1752776262] 'process raft request' (duration: 554.530577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:37.381986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.48922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-27T13:46:37.382008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.842056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-27T13:46:37.382029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.018494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"warn","ts":"2026-05-27T13:46:37.382064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.54189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T13:46:37.38207Z","caller":"traceutil/trace.go:171","msg":"trace[542828708] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"567.943446ms","start":"2026-05-27T13:46:36.814108Z","end":"2026-05-27T13:46:37.382051Z","steps":["trace[542828708] 'agreement among raft nodes before linearized reading' (duration: 567.840976ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.382094Z","caller":"traceutil/trace.go:171","msg":"trace[1171319405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"620.582371ms","start":"2026-05-27T13:46:36.761504Z","end":"2026-05-27T13:46:37.382086Z","steps":["trace[1171319405] 'agreement among raft nodes before linearized reading' (duration: 620.53767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:37.382124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.761499Z","time spent":"620.618581ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T13:46:37.382155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.99949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-27T13:46:37.382121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.814086Z","time spent":"568.022997ms","remote":"127.0.0.1:34192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-27T13:46:37.382197Z","caller":"traceutil/trace.go:171","msg":"trace[1872177409] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"555.054641ms","start":"2026-05-27T13:46:36.827127Z","end":"2026-05-27T13:46:37.382181Z","steps":["trace[1872177409] 'agreement among raft nodes before linearized reading' (duration: 554.988631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:37.382205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.961083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-27T13:46:37.382165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.485498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T13:46:37.382295Z","caller":"traceutil/trace.go:171","msg":"trace[738621340] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"411.649529ms","start":"2026-05-27T13:46:36.970631Z","end":"2026-05-27T13:46:37.38228Z","steps":["trace[738621340] 'agreement among raft nodes before linearized reading' (duration: 411.479348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:37.382303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.999766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T13:46:37.382363Z","caller":"traceutil/trace.go:171","msg":"trace[302752630] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"504.133366ms","start":"2026-05-27T13:46:36.878219Z","end":"2026-05-27T13:46:37.382353Z","steps":["trace[302752630] 'agreement among raft nodes before linearized reading' (duration: 504.048616ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.382334Z","caller":"traceutil/trace.go:171","msg":"trace[96398103] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"540.977059ms","start":"2026-05-27T13:46:36.841298Z","end":"2026-05-27T13:46:37.382275Z","steps":["trace[96398103] 'process raft request' (duration: 540.198714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:37.382384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.970606Z","time spent":"411.739349ms","remote":"127.0.0.1:34454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-27T13:46:37.382024Z","caller":"traceutil/trace.go:171","msg":"trace[680982962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"620.540381ms","start":"2026-05-27T13:46:36.761474Z","end":"2026-05-27T13:46:37.382015Z","steps":["trace[680982962] 'agreement among raft nodes before linearized reading' (duration: 620.471301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:37.382542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.841285Z","time spent":"541.15449ms","remote":"127.0.0.1:34240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:37.382588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.7611Z","time spent":"620.185858ms","remote":"127.0.0.1:34122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:46:37.382594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.761469Z","time spent":"621.084675ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T13:46:37.382663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.826271Z","time spent":"555.538344ms","remote":"127.0.0.1:34512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:37.382404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.878176Z","time spent":"504.220028ms","remote":"127.0.0.1:34192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-27T13:46:37.382078Z","caller":"traceutil/trace.go:171","msg":"trace[466056009] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"621.075594ms","start":"2026-05-27T13:46:36.760989Z","end":"2026-05-27T13:46:37.382065Z","steps":["trace[466056009] 'agreement among raft nodes before linearized reading' (duration: 620.966954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:37.382902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.76097Z","time spent":"621.915952ms","remote":"127.0.0.1:34240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-27T13:46:37.382239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.827116Z","time spent":"555.110352ms","remote":"127.0.0.1:34156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-05-27T13:46:37.382241Z","caller":"traceutil/trace.go:171","msg":"trace[1178989638] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"557.035835ms","start":"2026-05-27T13:46:36.825196Z","end":"2026-05-27T13:46:37.382232Z","steps":["trace[1178989638] 'agreement among raft nodes before linearized reading' (duration: 556.982564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:37.382584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.826574Z","time spent":"555.133441ms","remote":"127.0.0.1:34586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:37.383105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.825164Z","time spent":"557.929061ms","remote":"127.0.0.1:34192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-27T13:46:37.381984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.221502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.188\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T13:46:37.383398Z","caller":"traceutil/trace.go:171","msg":"trace[167046797] range","detail":"{range_begin:/registry/masterleases/199.19.213.188; range_end:; response_count:0; response_revision:11; }","duration":"559.681522ms","start":"2026-05-27T13:46:36.823703Z","end":"2026-05-27T13:46:37.383384Z","steps":["trace[167046797] 'agreement among raft nodes before linearized reading' (duration: 558.165791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:37.383435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.826737Z","time spent":"555.177611ms","remote":"127.0.0.1:34586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:37.38364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.837659Z","time spent":"544.211459ms","remote":"127.0.0.1:34318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:37.383445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.823669Z","time spent":"559.765303ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.188\" "} {"level":"warn","ts":"2026-05-27T13:46:37.383777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.826653Z","time spent":"555.279773ms","remote":"127.0.0.1:34586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:37.3834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.826507Z","time spent":"555.331162ms","remote":"127.0.0.1:34586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:37.383974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:36.826841Z","time spent":"555.149391ms","remote":"127.0.0.1:34586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:46:37.585548Z","caller":"traceutil/trace.go:171","msg":"trace[185699504] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"195.882393ms","start":"2026-05-27T13:46:37.389628Z","end":"2026-05-27T13:46:37.58551Z","steps":["trace[185699504] 'read index received' (duration: 163.099888ms)","trace[185699504] 'applied index is now lower than readState.Index' (duration: 32.780885ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:37.585591Z","caller":"traceutil/trace.go:171","msg":"trace[816756025] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"196.099104ms","start":"2026-05-27T13:46:37.389442Z","end":"2026-05-27T13:46:37.585541Z","steps":["trace[816756025] 'process raft request' (duration: 163.362489ms)","trace[816756025] 'compare' (duration: 32.530294ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:37.585818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.163425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T13:46:37.585877Z","caller":"traceutil/trace.go:171","msg":"trace[1971844395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"196.307587ms","start":"2026-05-27T13:46:37.389553Z","end":"2026-05-27T13:46:37.58586Z","steps":["trace[1971844395] 'agreement among raft nodes before linearized reading' (duration: 196.128615ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.618883Z","caller":"traceutil/trace.go:171","msg":"trace[604298029] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"229.193472ms","start":"2026-05-27T13:46:37.389663Z","end":"2026-05-27T13:46:37.618856Z","steps":["trace[604298029] 'process raft request' (duration: 228.93079ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.619065Z","caller":"traceutil/trace.go:171","msg":"trace[1481019609] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"229.204102ms","start":"2026-05-27T13:46:37.389847Z","end":"2026-05-27T13:46:37.619051Z","steps":["trace[1481019609] 'process raft request' (duration: 228.95161ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.619177Z","caller":"traceutil/trace.go:171","msg":"trace[208409598] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"229.398113ms","start":"2026-05-27T13:46:37.389767Z","end":"2026-05-27T13:46:37.619165Z","steps":["trace[208409598] 'process raft request' (duration: 228.99366ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.619179Z","caller":"traceutil/trace.go:171","msg":"trace[1831196270] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"228.498887ms","start":"2026-05-27T13:46:37.390644Z","end":"2026-05-27T13:46:37.619142Z","steps":["trace[1831196270] 'process raft request' (duration: 228.206595ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.619326Z","caller":"traceutil/trace.go:171","msg":"trace[387051680] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"213.042955ms","start":"2026-05-27T13:46:37.406268Z","end":"2026-05-27T13:46:37.619311Z","steps":["trace[387051680] 'process raft request' (duration: 212.811743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:37.619461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.578267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-05-27T13:46:37.619458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.428558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-27T13:46:37.619501Z","caller":"traceutil/trace.go:171","msg":"trace[1044338162] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"175.641607ms","start":"2026-05-27T13:46:37.443849Z","end":"2026-05-27T13:46:37.61949Z","steps":["trace[1044338162] 'agreement among raft nodes before linearized reading' (duration: 175.524576ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.619514Z","caller":"traceutil/trace.go:171","msg":"trace[1792461768] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"164.545028ms","start":"2026-05-27T13:46:37.454953Z","end":"2026-05-27T13:46:37.619498Z","steps":["trace[1792461768] 'agreement among raft nodes before linearized reading' (duration: 164.355917ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.619511Z","caller":"traceutil/trace.go:171","msg":"trace[624251585] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"216.469879ms","start":"2026-05-27T13:46:37.403023Z","end":"2026-05-27T13:46:37.619492Z","steps":["trace[624251585] 'process raft request' (duration: 215.909635ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.619541Z","caller":"traceutil/trace.go:171","msg":"trace[1561889768] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"228.734818ms","start":"2026-05-27T13:46:37.390794Z","end":"2026-05-27T13:46:37.619529Z","steps":["trace[1561889768] 'process raft request' (duration: 228.178795ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.619522Z","caller":"traceutil/trace.go:171","msg":"trace[1605471228] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"215.738384ms","start":"2026-05-27T13:46:37.403737Z","end":"2026-05-27T13:46:37.619475Z","steps":["trace[1605471228] 'process raft request' (duration: 215.273601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:37.61964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.77731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T13:46:37.619674Z","caller":"traceutil/trace.go:171","msg":"trace[503730754] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:0; response_revision:20; }","duration":"178.8429ms","start":"2026-05-27T13:46:37.440823Z","end":"2026-05-27T13:46:37.619666Z","steps":["trace[503730754] 'agreement among raft nodes before linearized reading' (duration: 178.7823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:37.619708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.251154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-48ljh\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-05-27T13:46:37.619754Z","caller":"traceutil/trace.go:171","msg":"trace[1201661924] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-48ljh; range_end:; response_count:1; response_revision:20; }","duration":"228.309085ms","start":"2026-05-27T13:46:37.391431Z","end":"2026-05-27T13:46:37.61974Z","steps":["trace[1201661924] 'agreement among raft nodes before linearized reading' (duration: 228.233974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:37.619768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.571205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-27T13:46:37.619902Z","caller":"traceutil/trace.go:171","msg":"trace[1220260918] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"228.90637ms","start":"2026-05-27T13:46:37.390968Z","end":"2026-05-27T13:46:37.619875Z","steps":["trace[1220260918] 'process raft request' (duration: 227.926313ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:37.619927Z","caller":"traceutil/trace.go:171","msg":"trace[2035713957] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"165.728776ms","start":"2026-05-27T13:46:37.454186Z","end":"2026-05-27T13:46:37.619914Z","steps":["trace[2035713957] 'agreement among raft nodes before linearized reading' (duration: 165.542135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:38.625214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.353605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-27T13:46:38.625388Z","caller":"traceutil/trace.go:171","msg":"trace[123662943] transaction","detail":"{read_only:false; response_revision:91; number_of_response:1; }","duration":"584.549056ms","start":"2026-05-27T13:46:38.040813Z","end":"2026-05-27T13:46:38.625362Z","steps":["trace[123662943] 'process raft request' (duration: 132.740888ms)","trace[123662943] 'compare' (duration: 451.121653ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:38.625467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:38.04078Z","time spent":"584.654538ms","remote":"127.0.0.1:34394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:38.665462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:38.098042Z","time spent":"567.412243ms","remote":"127.0.0.1:34142","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-27T13:46:38.66551Z","caller":"traceutil/trace.go:171","msg":"trace[1524825292] linearizableReadLoop","detail":"{readStateIndex:97; appliedIndex:95; }","duration":"276.010987ms","start":"2026-05-27T13:46:38.389448Z","end":"2026-05-27T13:46:38.665459Z","steps":["trace[1524825292] 'read index received' (duration: 275.565614ms)","trace[1524825292] 'applied index is now lower than readState.Index' (duration: 444.173µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:38.665662Z","caller":"traceutil/trace.go:171","msg":"trace[1616188377] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"497.078969ms","start":"2026-05-27T13:46:38.168566Z","end":"2026-05-27T13:46:38.665645Z","steps":["trace[1616188377] 'process raft request' (duration: 496.772087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:38.665799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:38.168527Z","time spent":"497.18484ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:38.666249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.782313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T13:46:38.666627Z","caller":"traceutil/trace.go:171","msg":"trace[117361109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:92; }","duration":"277.202957ms","start":"2026-05-27T13:46:38.389395Z","end":"2026-05-27T13:46:38.666598Z","steps":["trace[117361109] 'agreement among raft nodes before linearized reading' (duration: 276.680673ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:38.770907Z","caller":"traceutil/trace.go:171","msg":"trace[1807790198] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"103.192339ms","start":"2026-05-27T13:46:38.667675Z","end":"2026-05-27T13:46:38.770868Z","steps":["trace[1807790198] 'process raft request' (duration: 58.495454ms)","trace[1807790198] 'compare' (duration: 44.430693ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:39.513377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.608335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:46:39.513491Z","caller":"traceutil/trace.go:171","msg":"trace[904793968] linearizableReadLoop","detail":"{readStateIndex:142; appliedIndex:141; }","duration":"123.323535ms","start":"2026-05-27T13:46:39.39015Z","end":"2026-05-27T13:46:39.513474Z","steps":["trace[904793968] 'read index received' (duration: 9.478488ms)","trace[904793968] 'applied index is now lower than readState.Index' (duration: 113.843487ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:39.513503Z","caller":"traceutil/trace.go:171","msg":"trace[1266195640] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"160.988618ms","start":"2026-05-27T13:46:39.352495Z","end":"2026-05-27T13:46:39.513484Z","steps":["trace[1266195640] 'process raft request' (duration: 47.203372ms)","trace[1266195640] 'compare' (duration: 113.438374ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:39.513626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.483607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18b370278571d837\" ","response":"range_response_count:1 size:656"} {"level":"info","ts":"2026-05-27T13:46:39.513672Z","caller":"traceutil/trace.go:171","msg":"trace[594172486] range","detail":"{range_begin:/registry/events/default/instance.18b370278571d837; range_end:; response_count:1; response_revision:137; }","duration":"123.596598ms","start":"2026-05-27T13:46:39.390064Z","end":"2026-05-27T13:46:39.51366Z","steps":["trace[594172486] 'agreement among raft nodes before linearized reading' (duration: 123.471877ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:39.97701Z","caller":"traceutil/trace.go:171","msg":"trace[1866934958] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"131.236172ms","start":"2026-05-27T13:46:39.84575Z","end":"2026-05-27T13:46:39.976986Z","steps":["trace[1866934958] 'process raft request' (duration: 108.891276ms)","trace[1866934958] 'compare' (duration: 22.208274ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:39.977204Z","caller":"traceutil/trace.go:171","msg":"trace[1608583976] transaction","detail":"{read_only:false; response_revision:156; number_of_response:1; }","duration":"131.421413ms","start":"2026-05-27T13:46:39.84573Z","end":"2026-05-27T13:46:39.977151Z","steps":["trace[1608583976] 'process raft request' (duration: 131.220022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:40.563635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.641967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:46:40.563938Z","caller":"traceutil/trace.go:171","msg":"trace[1428006104] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"252.12195ms","start":"2026-05-27T13:46:40.311768Z","end":"2026-05-27T13:46:40.56389Z","steps":["trace[1428006104] 'process raft request' (duration: 136.114039ms)","trace[1428006104] 'compare' (duration: 115.458706ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:40.563941Z","caller":"traceutil/trace.go:171","msg":"trace[551704793] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"210.790373ms","start":"2026-05-27T13:46:40.353111Z","end":"2026-05-27T13:46:40.563901Z","steps":["trace[551704793] 'process raft request' (duration: 210.650921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:41.282867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.900017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:46:41.283069Z","caller":"traceutil/trace.go:171","msg":"trace[1257779976] transaction","detail":"{read_only:false; response_revision:182; number_of_response:1; }","duration":"309.748347ms","start":"2026-05-27T13:46:40.973238Z","end":"2026-05-27T13:46:41.282986Z","steps":["trace[1257779976] 'process raft request' (duration: 76.623378ms)","trace[1257779976] 'compare' (duration: 232.475013ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:41.283157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:40.973224Z","time spent":"309.899629ms","remote":"127.0.0.1:34394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:41.648439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.016148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:46:41.648718Z","caller":"traceutil/trace.go:171","msg":"trace[1945959680] transaction","detail":"{read_only:false; response_revision:184; number_of_response:1; }","duration":"325.227429ms","start":"2026-05-27T13:46:41.323435Z","end":"2026-05-27T13:46:41.648663Z","steps":["trace[1945959680] 'process raft request' (duration: 72.882118ms)","trace[1945959680] 'compare' (duration: 251.663745ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:41.648937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:41.323414Z","time spent":"325.434291ms","remote":"127.0.0.1:34394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:46:44.070375Z","caller":"traceutil/trace.go:171","msg":"trace[519753416] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"101.775197ms","start":"2026-05-27T13:46:43.968542Z","end":"2026-05-27T13:46:44.070317Z","steps":["trace[519753416] 'process raft request' (duration: 72.759916ms)","trace[519753416] 'compare' (duration: 28.738078ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:44.576864Z","caller":"traceutil/trace.go:171","msg":"trace[475538955] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"108.770372ms","start":"2026-05-27T13:46:44.468064Z","end":"2026-05-27T13:46:44.576834Z","steps":["trace[475538955] 'process raft request' (duration: 108.733411ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:44.576934Z","caller":"traceutil/trace.go:171","msg":"trace[1384660721] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"158.503332ms","start":"2026-05-27T13:46:44.418364Z","end":"2026-05-27T13:46:44.576867Z","steps":["trace[1384660721] 'process raft request' (duration: 118.276977ms)","trace[1384660721] 'compare' (duration: 40.003792ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:44.576954Z","caller":"traceutil/trace.go:171","msg":"trace[1141357301] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:303; }","duration":"112.027188ms","start":"2026-05-27T13:46:44.464877Z","end":"2026-05-27T13:46:44.576904Z","steps":["trace[1141357301] 'read index received' (duration: 71.831013ms)","trace[1141357301] 'applied index is now lower than readState.Index' (duration: 40.187045ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:44.577234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.317611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:46:44.577292Z","caller":"traceutil/trace.go:171","msg":"trace[501845029] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:299; }","duration":"112.439012ms","start":"2026-05-27T13:46:44.464838Z","end":"2026-05-27T13:46:44.577277Z","steps":["trace[501845029] 'agreement among raft nodes before linearized reading' (duration: 112.150589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.07733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.92336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-27T13:46:45.077495Z","caller":"traceutil/trace.go:171","msg":"trace[2039791848] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:305; }","duration":"500.419858ms","start":"2026-05-27T13:46:44.57705Z","end":"2026-05-27T13:46:45.07747Z","steps":["trace[2039791848] 'read index received' (duration: 184.337256ms)","trace[2039791848] 'applied index is now lower than readState.Index' (duration: 316.080502ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:45.077591Z","caller":"traceutil/trace.go:171","msg":"trace[1720767535] transaction","detail":"{read_only:false; number_of_response:0; response_revision:299; }","duration":"504.000197ms","start":"2026-05-27T13:46:44.573527Z","end":"2026-05-27T13:46:45.077527Z","steps":["trace[1720767535] 'process raft request' (duration: 187.671463ms)","trace[1720767535] 'compare' (duration: 315.794048ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:45.077745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.664071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-05-27T13:46:45.077844Z","caller":"traceutil/trace.go:171","msg":"trace[769952863] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:299; }","duration":"605.770233ms","start":"2026-05-27T13:46:44.472045Z","end":"2026-05-27T13:46:45.077816Z","steps":["trace[769952863] 'agreement among raft nodes before linearized reading' (duration: 605.595721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.077847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:44.57349Z","time spent":"504.241521ms","remote":"127.0.0.1:34240","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:45.077713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.967567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-27T13:46:45.077906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:44.47204Z","time spent":"605.856964ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-05-27T13:46:45.077926Z","caller":"traceutil/trace.go:171","msg":"trace[258479519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:299; }","duration":"580.257111ms","start":"2026-05-27T13:46:44.49765Z","end":"2026-05-27T13:46:45.077907Z","steps":["trace[258479519] 'agreement among raft nodes before linearized reading' (duration: 579.893417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.07766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.421209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-05-27T13:46:45.077981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:44.497626Z","time spent":"580.340701ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T13:46:45.078041Z","caller":"traceutil/trace.go:171","msg":"trace[1283463853] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:299; }","duration":"605.863143ms","start":"2026-05-27T13:46:44.472155Z","end":"2026-05-27T13:46:45.078018Z","steps":["trace[1283463853] 'agreement among raft nodes before linearized reading' (duration: 605.417618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.078091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:44.47213Z","time spent":"605.947815ms","remote":"127.0.0.1:34454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-27T13:46:45.123624Z","caller":"traceutil/trace.go:171","msg":"trace[873983234] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"542.458687ms","start":"2026-05-27T13:46:44.58111Z","end":"2026-05-27T13:46:45.123569Z","steps":["trace[873983234] 'process raft request' (duration: 542.147143ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:45.123694Z","caller":"traceutil/trace.go:171","msg":"trace[1309545671] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"542.539898ms","start":"2026-05-27T13:46:44.58113Z","end":"2026-05-27T13:46:45.12367Z","steps":["trace[1309545671] 'process raft request' (duration: 542.319346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.123839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:44.581108Z","time spent":"542.640499ms","remote":"127.0.0.1:34394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":373,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:45.123843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:44.581085Z","time spent":"542.66342ms","remote":"127.0.0.1:34142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:46:45.123871Z","caller":"traceutil/trace.go:171","msg":"trace[150796325] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"525.439169ms","start":"2026-05-27T13:46:44.598383Z","end":"2026-05-27T13:46:45.123822Z","steps":["trace[150796325] 'process raft request' (duration: 525.252057ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:45.123972Z","caller":"traceutil/trace.go:171","msg":"trace[980776773] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"525.058175ms","start":"2026-05-27T13:46:44.598906Z","end":"2026-05-27T13:46:45.123964Z","steps":["trace[980776773] 'process raft request' (duration: 524.825033ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:45.124007Z","caller":"traceutil/trace.go:171","msg":"trace[1720432152] transaction","detail":"{read_only:false; number_of_response:0; response_revision:301; }","duration":"525.721523ms","start":"2026-05-27T13:46:44.598274Z","end":"2026-05-27T13:46:45.123995Z","steps":["trace[1720432152] 'process raft request' (duration: 525.278478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.12403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:44.59888Z","time spent":"525.118816ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:45.124078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:44.598372Z","time spent":"525.630082ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:46:45.123934Z","caller":"traceutil/trace.go:171","msg":"trace[1895695701] transaction","detail":"{read_only:false; number_of_response:0; response_revision:303; }","duration":"524.761021ms","start":"2026-05-27T13:46:44.599155Z","end":"2026-05-27T13:46:45.123916Z","steps":["trace[1895695701] 'process raft request' (duration: 524.619039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.124148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:44.598252Z","time spent":"525.811003ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:45.124194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.587259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-05-27T13:46:45.12425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:44.59912Z","time spent":"525.061155ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:46:45.124298Z","caller":"traceutil/trace.go:171","msg":"trace[567099754] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:303; }","duration":"543.751582ms","start":"2026-05-27T13:46:44.580511Z","end":"2026-05-27T13:46:45.124262Z","steps":["trace[567099754] 'agreement among raft nodes before linearized reading' (duration: 543.453068ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:45.12391Z","caller":"traceutil/trace.go:171","msg":"trace[1705708157] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"525.107965ms","start":"2026-05-27T13:46:44.598769Z","end":"2026-05-27T13:46:45.123877Z","steps":["trace[1705708157] 'process raft request' (duration: 524.921003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.124359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:44.580496Z","time spent":"543.854573ms","remote":"127.0.0.1:34192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-27T13:46:45.12439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:44.598758Z","time spent":"525.597151ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:45.503126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.069336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:46:45.503391Z","caller":"traceutil/trace.go:171","msg":"trace[460514729] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:313; }","duration":"379.553341ms","start":"2026-05-27T13:46:45.123816Z","end":"2026-05-27T13:46:45.503369Z","steps":["trace[460514729] 'read index received' (duration: 77.117801ms)","trace[460514729] 'applied index is now lower than readState.Index' (duration: 302.4342ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:45.503482Z","caller":"traceutil/trace.go:171","msg":"trace[1227855120] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"420.196052ms","start":"2026-05-27T13:46:45.083275Z","end":"2026-05-27T13:46:45.503471Z","steps":["trace[1227855120] 'process raft request' (duration: 420.01868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.503543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.083258Z","time spent":"420.251803ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:46:45.503567Z","caller":"traceutil/trace.go:171","msg":"trace[716220523] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"420.994872ms","start":"2026-05-27T13:46:45.082514Z","end":"2026-05-27T13:46:45.503509Z","steps":["trace[716220523] 'process raft request' (duration: 118.40697ms)","trace[716220523] 'compare' (duration: 301.824363ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:45.503724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.082495Z","time spent":"421.151333ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:46:45.503719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.868865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-27T13:46:45.503787Z","caller":"traceutil/trace.go:171","msg":"trace[605721828] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:305; }","duration":"424.006327ms","start":"2026-05-27T13:46:45.079759Z","end":"2026-05-27T13:46:45.503765Z","steps":["trace[605721828] 'agreement among raft nodes before linearized reading' (duration: 423.834155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.503861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.079704Z","time spent":"424.128257ms","remote":"127.0.0.1:34240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-27T13:46:45.503972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.451348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-27T13:46:45.504076Z","caller":"traceutil/trace.go:171","msg":"trace[1201924102] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:305; }","duration":"422.649481ms","start":"2026-05-27T13:46:45.081398Z","end":"2026-05-27T13:46:45.504048Z","steps":["trace[1201924102] 'agreement among raft nodes before linearized reading' (duration: 422.142374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.504118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.081377Z","time spent":"422.731501ms","remote":"127.0.0.1:34454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-27T13:46:45.56536Z","caller":"traceutil/trace.go:171","msg":"trace[186641718] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"436.018795ms","start":"2026-05-27T13:46:45.12928Z","end":"2026-05-27T13:46:45.565299Z","steps":["trace[186641718] 'process raft request' (duration: 435.878473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.565443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.332593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-27T13:46:45.565431Z","caller":"traceutil/trace.go:171","msg":"trace[1655989014] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"435.705111ms","start":"2026-05-27T13:46:45.129693Z","end":"2026-05-27T13:46:45.565399Z","steps":["trace[1655989014] 'process raft request' (duration: 435.513979ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:45.565426Z","caller":"traceutil/trace.go:171","msg":"trace[841271877] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"436.202617ms","start":"2026-05-27T13:46:45.129184Z","end":"2026-05-27T13:46:45.565386Z","steps":["trace[841271877] 'process raft request' (duration: 435.822312ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:45.565505Z","caller":"traceutil/trace.go:171","msg":"trace[1524286339] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:308; }","duration":"439.438664ms","start":"2026-05-27T13:46:45.126056Z","end":"2026-05-27T13:46:45.565495Z","steps":["trace[1524286339] 'agreement among raft nodes before linearized reading' (duration: 439.271462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.565536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.12604Z","time spent":"439.490344ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-27T13:46:45.565596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.129667Z","time spent":"435.851763ms","remote":"127.0.0.1:34266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:45.565617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.129165Z","time spent":"436.366278ms","remote":"127.0.0.1:34408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":400,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:45.565617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.538316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"warn","ts":"2026-05-27T13:46:45.565627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.129263Z","time spent":"436.216097ms","remote":"127.0.0.1:34142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:46:45.565676Z","caller":"traceutil/trace.go:171","msg":"trace[1177365728] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:308; }","duration":"439.642438ms","start":"2026-05-27T13:46:45.126018Z","end":"2026-05-27T13:46:45.565661Z","steps":["trace[1177365728] 'agreement among raft nodes before linearized reading' (duration: 439.538875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.565722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.125993Z","time spent":"439.717438ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4686,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-05-27T13:46:45.565854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.786819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6964"} {"level":"info","ts":"2026-05-27T13:46:45.565874Z","caller":"traceutil/trace.go:171","msg":"trace[1277071170] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:308; }","duration":"439.84023ms","start":"2026-05-27T13:46:45.126028Z","end":"2026-05-27T13:46:45.565868Z","steps":["trace[1277071170] 'agreement among raft nodes before linearized reading' (duration: 439.794439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.565892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.126007Z","time spent":"439.88128ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6987,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-05-27T13:46:45.960276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.361577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-27T13:46:45.960453Z","caller":"traceutil/trace.go:171","msg":"trace[279414019] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:318; }","duration":"395.159992ms","start":"2026-05-27T13:46:45.565266Z","end":"2026-05-27T13:46:45.960426Z","steps":["trace[279414019] 'read index received' (duration: 201.577243ms)","trace[279414019] 'applied index is now lower than readState.Index' (duration: 193.580419ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:45.960502Z","caller":"traceutil/trace.go:171","msg":"trace[151814121] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"443.006456ms","start":"2026-05-27T13:46:45.517471Z","end":"2026-05-27T13:46:45.960478Z","steps":["trace[151814121] 'process raft request' (duration: 249.337425ms)","trace[151814121] 'compare' (duration: 193.291477ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:45.960606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.789467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"warn","ts":"2026-05-27T13:46:45.960606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.714858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"warn","ts":"2026-05-27T13:46:45.960617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.517455Z","time spent":"443.106328ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:46:45.960644Z","caller":"traceutil/trace.go:171","msg":"trace[386810882] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:308; }","duration":"444.857058ms","start":"2026-05-27T13:46:45.515778Z","end":"2026-05-27T13:46:45.960635Z","steps":["trace[386810882] 'agreement among raft nodes before linearized reading' (duration: 444.728135ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:45.960658Z","caller":"traceutil/trace.go:171","msg":"trace[724052574] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:308; }","duration":"452.81264ms","start":"2026-05-27T13:46:45.50783Z","end":"2026-05-27T13:46:45.960643Z","steps":["trace[724052574] 'agreement among raft nodes before linearized reading' (duration: 452.706338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:45.960695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.515766Z","time spent":"444.921278ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4686,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-05-27T13:46:45.96071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.507807Z","time spent":"452.893221ms","remote":"127.0.0.1:34240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-27T13:46:46.005778Z","caller":"traceutil/trace.go:171","msg":"trace[1191094349] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"433.827351ms","start":"2026-05-27T13:46:45.571925Z","end":"2026-05-27T13:46:46.005752Z","steps":["trace[1191094349] 'process raft request' (duration: 433.584228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:46.006013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.571909Z","time spent":"433.945972ms","remote":"127.0.0.1:34142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:46:46.006113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.56468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-27T13:46:46.006183Z","caller":"traceutil/trace.go:171","msg":"trace[1050801206] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:310; }","duration":"434.673671ms","start":"2026-05-27T13:46:45.571488Z","end":"2026-05-27T13:46:46.006162Z","steps":["trace[1050801206] 'agreement among raft nodes before linearized reading' (duration: 434.549569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:46.006238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.571468Z","time spent":"434.758722ms","remote":"127.0.0.1:34266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-05-27T13:46:46.006211Z","caller":"traceutil/trace.go:171","msg":"trace[771731276] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"433.917053ms","start":"2026-05-27T13:46:45.57222Z","end":"2026-05-27T13:46:46.006137Z","steps":["trace[771731276] 'process raft request' (duration: 433.441827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:46.006484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.572187Z","time spent":"434.188305ms","remote":"127.0.0.1:34266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:46:46.275231Z","caller":"traceutil/trace.go:171","msg":"trace[1210542523] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:321; }","duration":"269.452235ms","start":"2026-05-27T13:46:46.005723Z","end":"2026-05-27T13:46:46.275176Z","steps":["trace[1210542523] 'read index received' (duration: 245.388104ms)","trace[1210542523] 'applied index is now lower than readState.Index' (duration: 24.063301ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:46.275587Z","caller":"traceutil/trace.go:171","msg":"trace[482421768] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"300.774799ms","start":"2026-05-27T13:46:45.974799Z","end":"2026-05-27T13:46:46.275574Z","steps":["trace[482421768] 'process raft request' (duration: 300.327654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:46.275708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.97479Z","time spent":"300.839831ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:46:46.275669Z","caller":"traceutil/trace.go:171","msg":"trace[1961769997] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"303.633223ms","start":"2026-05-27T13:46:45.971997Z","end":"2026-05-27T13:46:46.27563Z","steps":["trace[1961769997] 'process raft request' (duration: 279.074346ms)","trace[1961769997] 'compare' (duration: 23.926279ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:46.275909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.971976Z","time spent":"303.832125ms","remote":"127.0.0.1:34240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4183,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:46:46.276017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.433918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-05-27T13:46:46.276224Z","caller":"traceutil/trace.go:171","msg":"trace[1951811818] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:312; }","duration":"314.68472ms","start":"2026-05-27T13:46:45.961521Z","end":"2026-05-27T13:46:46.276205Z","steps":["trace[1951811818] 'agreement among raft nodes before linearized reading' (duration: 314.300716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:46.276349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:45.961504Z","time spent":"314.800672ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5920,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-05-27T13:46:46.306497Z","caller":"traceutil/trace.go:171","msg":"trace[334478029] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"294.406137ms","start":"2026-05-27T13:46:46.01204Z","end":"2026-05-27T13:46:46.306446Z","steps":["trace[334478029] 'process raft request' (duration: 294.125854ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:46.306523Z","caller":"traceutil/trace.go:171","msg":"trace[2020316473] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"293.465366ms","start":"2026-05-27T13:46:46.013027Z","end":"2026-05-27T13:46:46.306493Z","steps":["trace[2020316473] 'process raft request' (duration: 293.219524ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:46.306609Z","caller":"traceutil/trace.go:171","msg":"trace[1372383064] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"291.393921ms","start":"2026-05-27T13:46:46.015196Z","end":"2026-05-27T13:46:46.30659Z","steps":["trace[1372383064] 'process raft request' (duration: 291.116277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:46.30673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.8606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:46:46.30684Z","caller":"traceutil/trace.go:171","msg":"trace[1412355183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:316; }","duration":"135.158073ms","start":"2026-05-27T13:46:46.171655Z","end":"2026-05-27T13:46:46.306813Z","steps":["trace[1412355183] 'agreement among raft nodes before linearized reading' (duration: 134.991961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:46.306916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.462153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:46:46.306983Z","caller":"traceutil/trace.go:171","msg":"trace[1974119004] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:316; }","duration":"292.572725ms","start":"2026-05-27T13:46:46.014393Z","end":"2026-05-27T13:46:46.306965Z","steps":["trace[1974119004] 'agreement among raft nodes before linearized reading' (duration: 292.462104ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:46.307131Z","caller":"traceutil/trace.go:171","msg":"trace[1770333083] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"297.469814ms","start":"2026-05-27T13:46:46.009644Z","end":"2026-05-27T13:46:46.307114Z","steps":["trace[1770333083] 'process raft request' (duration: 296.32911ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:46.456648Z","caller":"traceutil/trace.go:171","msg":"trace[1814785383] linearizableReadLoop","detail":"{readStateIndex:332; appliedIndex:328; }","duration":"135.185114ms","start":"2026-05-27T13:46:46.321437Z","end":"2026-05-27T13:46:46.456623Z","steps":["trace[1814785383] 'read index received' (duration: 49.096353ms)","trace[1814785383] 'applied index is now lower than readState.Index' (duration: 86.087191ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:46.456811Z","caller":"traceutil/trace.go:171","msg":"trace[1580223092] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"137.36971ms","start":"2026-05-27T13:46:46.319427Z","end":"2026-05-27T13:46:46.456796Z","steps":["trace[1580223092] 'process raft request' (duration: 89.36628ms)","trace[1580223092] 'compare' (duration: 47.621416ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:46.457141Z","caller":"traceutil/trace.go:171","msg":"trace[1716842196] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"135.68217ms","start":"2026-05-27T13:46:46.321399Z","end":"2026-05-27T13:46:46.457081Z","steps":["trace[1716842196] 'process raft request' (duration: 135.156254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:46.457181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.574879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-27T13:46:46.457276Z","caller":"traceutil/trace.go:171","msg":"trace[1067219192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:321; }","duration":"135.843853ms","start":"2026-05-27T13:46:46.32141Z","end":"2026-05-27T13:46:46.457254Z","steps":["trace[1067219192] 'agreement among raft nodes before linearized reading' (duration: 135.393367ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:47.845356Z","caller":"traceutil/trace.go:171","msg":"trace[1533731910] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"247.788909ms","start":"2026-05-27T13:46:47.597543Z","end":"2026-05-27T13:46:47.845332Z","steps":["trace[1533731910] 'process raft request' (duration: 172.848187ms)","trace[1533731910] 'compare' (duration: 74.73351ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:47.845831Z","caller":"traceutil/trace.go:171","msg":"trace[1548169448] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"244.260995ms","start":"2026-05-27T13:46:47.601534Z","end":"2026-05-27T13:46:47.845795Z","steps":["trace[1548169448] 'process raft request' (duration: 243.744649ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:47.845944Z","caller":"traceutil/trace.go:171","msg":"trace[1150494805] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"242.096009ms","start":"2026-05-27T13:46:47.603837Z","end":"2026-05-27T13:46:47.845933Z","steps":["trace[1150494805] 'process raft request' (duration: 241.721793ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:47.846052Z","caller":"traceutil/trace.go:171","msg":"trace[1127856541] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"220.250593ms","start":"2026-05-27T13:46:47.625785Z","end":"2026-05-27T13:46:47.846036Z","steps":["trace[1127856541] 'process raft request' (duration: 220.113301ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:47.846105Z","caller":"traceutil/trace.go:171","msg":"trace[35735010] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:373; }","duration":"233.992027ms","start":"2026-05-27T13:46:47.612102Z","end":"2026-05-27T13:46:47.846094Z","steps":["trace[35735010] 'read index received' (duration: 8.397766ms)","trace[35735010] 'applied index is now lower than readState.Index' (duration: 225.593281ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:47.846238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.113918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-27T13:46:47.84626Z","caller":"traceutil/trace.go:171","msg":"trace[1010517339] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:367; }","duration":"234.190379ms","start":"2026-05-27T13:46:47.612063Z","end":"2026-05-27T13:46:47.846253Z","steps":["trace[1010517339] 'agreement among raft nodes before linearized reading' (duration: 234.100167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:47.8463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.726211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-27T13:46:47.846337Z","caller":"traceutil/trace.go:171","msg":"trace[597237160] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:367; }","duration":"155.826682ms","start":"2026-05-27T13:46:47.690499Z","end":"2026-05-27T13:46:47.846326Z","steps":["trace[597237160] 'agreement among raft nodes before linearized reading' (duration: 155.730741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:47.846448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.42093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-27T13:46:47.846469Z","caller":"traceutil/trace.go:171","msg":"trace[85498732] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:367; }","duration":"133.46596ms","start":"2026-05-27T13:46:47.712995Z","end":"2026-05-27T13:46:47.846461Z","steps":["trace[85498732] 'agreement among raft nodes before linearized reading' (duration: 133.42962ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.16488Z","caller":"traceutil/trace.go:171","msg":"trace[1021694571] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"215.122498ms","start":"2026-05-27T13:46:47.949728Z","end":"2026-05-27T13:46:48.16485Z","steps":["trace[1021694571] 'read index received' (duration: 127.625983ms)","trace[1021694571] 'applied index is now lower than readState.Index' (duration: 87.495345ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:48.165007Z","caller":"traceutil/trace.go:171","msg":"trace[1696176935] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"296.174328ms","start":"2026-05-27T13:46:47.868804Z","end":"2026-05-27T13:46:48.164978Z","steps":["trace[1696176935] 'process raft request' (duration: 208.532131ms)","trace[1696176935] 'compare' (duration: 87.359343ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:48.165189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.322141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-27T13:46:48.165244Z","caller":"traceutil/trace.go:171","msg":"trace[1864601940] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:372; }","duration":"215.574364ms","start":"2026-05-27T13:46:47.949662Z","end":"2026-05-27T13:46:48.165236Z","steps":["trace[1864601940] 'agreement among raft nodes before linearized reading' (duration: 215.31747ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.172935Z","caller":"traceutil/trace.go:171","msg":"trace[1223244696] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"220.998797ms","start":"2026-05-27T13:46:47.951913Z","end":"2026-05-27T13:46:48.172912Z","steps":["trace[1223244696] 'process raft request' (duration: 220.877625ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.173287Z","caller":"traceutil/trace.go:171","msg":"trace[1845451481] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"216.122855ms","start":"2026-05-27T13:46:47.957122Z","end":"2026-05-27T13:46:48.173245Z","steps":["trace[1845451481] 'process raft request' (duration: 215.73359ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.173476Z","caller":"traceutil/trace.go:171","msg":"trace[1113735673] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"142.623255ms","start":"2026-05-27T13:46:48.030814Z","end":"2026-05-27T13:46:48.173437Z","steps":["trace[1113735673] 'process raft request' (duration: 142.361802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:48.173583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.676044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-05-27T13:46:48.173723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.646025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-05-27T13:46:48.173808Z","caller":"traceutil/trace.go:171","msg":"trace[1283117736] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:376; }","duration":"182.791817ms","start":"2026-05-27T13:46:47.990998Z","end":"2026-05-27T13:46:48.17379Z","steps":["trace[1283117736] 'agreement among raft nodes before linearized reading' (duration: 182.633235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:48.173915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.800869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"warn","ts":"2026-05-27T13:46:48.173952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.662067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-27T13:46:48.173997Z","caller":"traceutil/trace.go:171","msg":"trace[405807411] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:376; }","duration":"183.739008ms","start":"2026-05-27T13:46:47.990244Z","end":"2026-05-27T13:46:48.173983Z","steps":["trace[405807411] 'agreement among raft nodes before linearized reading' (duration: 183.602717ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.173773Z","caller":"traceutil/trace.go:171","msg":"trace[322378978] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:376; }","duration":"182.866608ms","start":"2026-05-27T13:46:47.990824Z","end":"2026-05-27T13:46:48.17369Z","steps":["trace[322378978] 'agreement among raft nodes before linearized reading' (duration: 182.548063ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.17402Z","caller":"traceutil/trace.go:171","msg":"trace[1824386265] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:376; }","duration":"183.935741ms","start":"2026-05-27T13:46:47.990069Z","end":"2026-05-27T13:46:48.174005Z","steps":["trace[1824386265] 'agreement among raft nodes before linearized reading' (duration: 183.766828ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.174316Z","caller":"traceutil/trace.go:171","msg":"trace[1250370194] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"223.700701ms","start":"2026-05-27T13:46:47.950586Z","end":"2026-05-27T13:46:48.174287Z","steps":["trace[1250370194] 'process raft request' (duration: 222.08739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:48.173752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.384993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"info","ts":"2026-05-27T13:46:48.176144Z","caller":"traceutil/trace.go:171","msg":"trace[1629259667] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:376; }","duration":"186.056787ms","start":"2026-05-27T13:46:47.990068Z","end":"2026-05-27T13:46:48.176125Z","steps":["trace[1629259667] 'agreement among raft nodes before linearized reading' (duration: 183.369292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:48.176276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.378269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6964"} {"level":"info","ts":"2026-05-27T13:46:48.17632Z","caller":"traceutil/trace.go:171","msg":"trace[1290891232] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:376; }","duration":"185.43889ms","start":"2026-05-27T13:46:47.990871Z","end":"2026-05-27T13:46:48.17631Z","steps":["trace[1290891232] 'agreement among raft nodes before linearized reading' (duration: 182.484401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:48.475931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.75821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:46:48.476233Z","caller":"traceutil/trace.go:171","msg":"trace[584640742] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"279.68495ms","start":"2026-05-27T13:46:48.196514Z","end":"2026-05-27T13:46:48.476199Z","steps":["trace[584640742] 'process raft request' (duration: 77.589475ms)","trace[584640742] 'compare' (duration: 201.570208ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:48.476389Z","caller":"traceutil/trace.go:171","msg":"trace[407472262] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:390; }","duration":"163.379448ms","start":"2026-05-27T13:46:48.312988Z","end":"2026-05-27T13:46:48.476367Z","steps":["trace[407472262] 'read index received' (duration: 56.622691ms)","trace[407472262] 'applied index is now lower than readState.Index' (duration: 106.754917ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:48.476519Z","caller":"traceutil/trace.go:171","msg":"trace[631342345] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"276.434278ms","start":"2026-05-27T13:46:48.200074Z","end":"2026-05-27T13:46:48.476508Z","steps":["trace[631342345] 'process raft request' (duration: 276.149195ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.476638Z","caller":"traceutil/trace.go:171","msg":"trace[1869617689] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"270.47357ms","start":"2026-05-27T13:46:48.20614Z","end":"2026-05-27T13:46:48.476614Z","steps":["trace[1869617689] 'process raft request' (duration: 270.123215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:48.476885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.823484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-27T13:46:48.476971Z","caller":"traceutil/trace.go:171","msg":"trace[1694357965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:383; }","duration":"163.925046ms","start":"2026-05-27T13:46:48.313033Z","end":"2026-05-27T13:46:48.476958Z","steps":["trace[1694357965] 'agreement among raft nodes before linearized reading' (duration: 163.761014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:48.477104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.925025ms","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-27T13:46:48.477237Z","caller":"traceutil/trace.go:171","msg":"trace[78503811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:383; }","duration":"164.24291ms","start":"2026-05-27T13:46:48.312962Z","end":"2026-05-27T13:46:48.477205Z","steps":["trace[78503811] 'agreement among raft nodes before linearized reading' (duration: 163.572372ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.477435Z","caller":"traceutil/trace.go:171","msg":"trace[1255244176] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"279.898763ms","start":"2026-05-27T13:46:48.197526Z","end":"2026-05-27T13:46:48.477424Z","steps":["trace[1255244176] 'process raft request' (duration: 278.632656ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.667714Z","caller":"traceutil/trace.go:171","msg":"trace[1755029139] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"183.837866ms","start":"2026-05-27T13:46:48.483843Z","end":"2026-05-27T13:46:48.66768Z","steps":["trace[1755029139] 'process raft request' (duration: 135.521384ms)","trace[1755029139] 'compare' (duration: 48.16489ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:48.73915Z","caller":"traceutil/trace.go:171","msg":"trace[776701786] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:394; }","duration":"175.0445ms","start":"2026-05-27T13:46:48.564072Z","end":"2026-05-27T13:46:48.739116Z","steps":["trace[776701786] 'read index received' (duration: 55.311794ms)","trace[776701786] 'applied index is now lower than readState.Index' (duration: 119.731116ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:48.739604Z","caller":"traceutil/trace.go:171","msg":"trace[563268491] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"254.865735ms","start":"2026-05-27T13:46:48.484712Z","end":"2026-05-27T13:46:48.739578Z","steps":["trace[563268491] 'process raft request' (duration: 253.988933ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.739744Z","caller":"traceutil/trace.go:171","msg":"trace[1762963060] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"252.86439ms","start":"2026-05-27T13:46:48.486826Z","end":"2026-05-27T13:46:48.73969Z","steps":["trace[1762963060] 'process raft request' (duration: 252.060228ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.739972Z","caller":"traceutil/trace.go:171","msg":"trace[617218372] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"251.676744ms","start":"2026-05-27T13:46:48.488265Z","end":"2026-05-27T13:46:48.739941Z","steps":["trace[617218372] 'process raft request' (duration: 250.727221ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.740219Z","caller":"traceutil/trace.go:171","msg":"trace[2084433231] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"251.752335ms","start":"2026-05-27T13:46:48.488456Z","end":"2026-05-27T13:46:48.740208Z","steps":["trace[2084433231] 'process raft request' (duration: 250.579139ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.740307Z","caller":"traceutil/trace.go:171","msg":"trace[1470136830] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"245.473693ms","start":"2026-05-27T13:46:48.494822Z","end":"2026-05-27T13:46:48.740296Z","steps":["trace[1470136830] 'process raft request' (duration: 244.248177ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:48.739514Z","caller":"traceutil/trace.go:171","msg":"trace[2039797736] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"251.830845ms","start":"2026-05-27T13:46:48.487665Z","end":"2026-05-27T13:46:48.739496Z","steps":["trace[2039797736] 'process raft request' (duration: 251.271967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:48.739414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.308963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:46:48.740994Z","caller":"traceutil/trace.go:171","msg":"trace[518468264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:390; }","duration":"176.957816ms","start":"2026-05-27T13:46:48.564018Z","end":"2026-05-27T13:46:48.740976Z","steps":["trace[518468264] 'agreement among raft nodes before linearized reading' (duration: 175.266163ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:49.240129Z","caller":"traceutil/trace.go:171","msg":"trace[1035008384] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"495.440719ms","start":"2026-05-27T13:46:48.744637Z","end":"2026-05-27T13:46:49.240078Z","steps":["trace[1035008384] 'process raft request' (duration: 492.819703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:49.240379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:48.744621Z","time spent":"495.611862ms","remote":"127.0.0.1:34142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:46:49.309723Z","caller":"traceutil/trace.go:171","msg":"trace[2045611320] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"564.507736ms","start":"2026-05-27T13:46:48.745188Z","end":"2026-05-27T13:46:49.309696Z","steps":["trace[2045611320] 'process raft request' (duration: 564.232413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:49.309859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:48.745177Z","time spent":"564.611277ms","remote":"127.0.0.1:34142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:46:49.309941Z","caller":"traceutil/trace.go:171","msg":"trace[1576885038] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"560.439264ms","start":"2026-05-27T13:46:48.749312Z","end":"2026-05-27T13:46:49.309751Z","steps":["trace[1576885038] 'process raft request' (duration: 560.373033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:49.310173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:48.749272Z","time spent":"560.786379ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:46:49.310193Z","caller":"traceutil/trace.go:171","msg":"trace[1375950053] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"562.521892ms","start":"2026-05-27T13:46:48.747657Z","end":"2026-05-27T13:46:49.310179Z","steps":["trace[1375950053] 'process raft request' (duration: 561.933774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:49.31052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:48.74764Z","time spent":"562.829975ms","remote":"127.0.0.1:34548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:46:49.44989Z","caller":"traceutil/trace.go:171","msg":"trace[927724528] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"130.373299ms","start":"2026-05-27T13:46:49.319482Z","end":"2026-05-27T13:46:49.449856Z","steps":["trace[927724528] 'process raft request' (duration: 90.076942ms)","trace[927724528] 'compare' (duration: 40.144895ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:50.628571Z","caller":"traceutil/trace.go:171","msg":"trace[1195369239] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"274.500739ms","start":"2026-05-27T13:46:50.354041Z","end":"2026-05-27T13:46:50.628541Z","steps":["trace[1195369239] 'process raft request' (duration: 274.311637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:51.202651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.316178ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-27T13:46:51.202769Z","caller":"traceutil/trace.go:171","msg":"trace[298167148] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:410; }","duration":"669.585317ms","start":"2026-05-27T13:46:50.533161Z","end":"2026-05-27T13:46:51.202746Z","steps":["trace[298167148] 'read index received' (duration: 95.180947ms)","trace[298167148] 'applied index is now lower than readState.Index' (duration: 574.40268ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:51.202783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:50.433715Z","time spent":"769.058026ms","remote":"127.0.0.1:34584","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-27T13:46:51.202928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.76387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-w25z2\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-05-27T13:46:51.202971Z","caller":"traceutil/trace.go:171","msg":"trace[1520435006] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-w25z2; range_end:; response_count:1; response_revision:400; }","duration":"669.851201ms","start":"2026-05-27T13:46:50.533107Z","end":"2026-05-27T13:46:51.202958Z","steps":["trace[1520435006] 'agreement among raft nodes before linearized reading' (duration: 669.747759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:51.203016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:50.533084Z","time spent":"669.923682ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4489,"request content":"key:\"/registry/pods/kube-system/kube-proxy-w25z2\" "} {"level":"warn","ts":"2026-05-27T13:46:51.203312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.214846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T13:46:51.203307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.511636ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:46:51.203388Z","caller":"traceutil/trace.go:171","msg":"trace[273958912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:400; }","duration":"638.320589ms","start":"2026-05-27T13:46:50.56505Z","end":"2026-05-27T13:46:51.203371Z","steps":["trace[273958912] 'agreement among raft nodes before linearized reading' (duration: 638.125736ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:51.203401Z","caller":"traceutil/trace.go:171","msg":"trace[657150616] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:400; }","duration":"425.616968ms","start":"2026-05-27T13:46:50.777767Z","end":"2026-05-27T13:46:51.203384Z","steps":["trace[657150616] 'agreement among raft nodes before linearized reading' (duration: 425.432335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:51.203436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:50.565039Z","time spent":"638.38939ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T13:46:51.203388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.881381ms","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-27T13:46:51.203497Z","caller":"traceutil/trace.go:171","msg":"trace[864417776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"458.032533ms","start":"2026-05-27T13:46:50.745441Z","end":"2026-05-27T13:46:51.203474Z","steps":["trace[864417776] 'agreement among raft nodes before linearized reading' (duration: 457.743029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:51.203636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:50.745427Z","time spent":"458.142635ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T13:46:51.203653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.381759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-27T13:46:51.203684Z","caller":"traceutil/trace.go:171","msg":"trace[46269273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:400; }","duration":"458.46649ms","start":"2026-05-27T13:46:50.745208Z","end":"2026-05-27T13:46:51.203675Z","steps":["trace[46269273] 'agreement among raft nodes before linearized reading' (duration: 458.398659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:51.203718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:50.745182Z","time spent":"458.529672ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T13:46:51.203521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.140945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T13:46:51.20383Z","caller":"traceutil/trace.go:171","msg":"trace[1196130807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:400; }","duration":"458.49658ms","start":"2026-05-27T13:46:50.745319Z","end":"2026-05-27T13:46:51.203816Z","steps":["trace[1196130807] 'agreement among raft nodes before linearized reading' (duration: 458.155455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:51.203881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:50.745282Z","time spent":"458.586371ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T13:46:51.42753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.359052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:46:51.427639Z","caller":"traceutil/trace.go:171","msg":"trace[1985501704] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"223.350866ms","start":"2026-05-27T13:46:51.20427Z","end":"2026-05-27T13:46:51.427621Z","steps":["trace[1985501704] 'process raft request' (duration: 121.830582ms)","trace[1985501704] 'compare' (duration: 101.19901ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:51.483385Z","caller":"traceutil/trace.go:171","msg":"trace[1759593952] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"274.784323ms","start":"2026-05-27T13:46:51.208575Z","end":"2026-05-27T13:46:51.483359Z","steps":["trace[1759593952] 'process raft request' (duration: 274.500559ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:51.483615Z","caller":"traceutil/trace.go:171","msg":"trace[23895132] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"274.613141ms","start":"2026-05-27T13:46:51.208982Z","end":"2026-05-27T13:46:51.483595Z","steps":["trace[23895132] 'process raft request' (duration: 274.335197ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:51.483831Z","caller":"traceutil/trace.go:171","msg":"trace[1536211960] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"274.766333ms","start":"2026-05-27T13:46:51.20905Z","end":"2026-05-27T13:46:51.483817Z","steps":["trace[1536211960] 'process raft request' (duration: 274.455089ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:51.483889Z","caller":"traceutil/trace.go:171","msg":"trace[1918462427] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"270.058764ms","start":"2026-05-27T13:46:51.213811Z","end":"2026-05-27T13:46:51.483869Z","steps":["trace[1918462427] 'process raft request' (duration: 269.865881ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:51.668226Z","caller":"traceutil/trace.go:171","msg":"trace[1206629164] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"174.874041ms","start":"2026-05-27T13:46:51.493327Z","end":"2026-05-27T13:46:51.668201Z","steps":["trace[1206629164] 'process raft request' (duration: 174.721279ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:51.818123Z","caller":"traceutil/trace.go:171","msg":"trace[579414450] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"321.810105ms","start":"2026-05-27T13:46:51.496263Z","end":"2026-05-27T13:46:51.818073Z","steps":["trace[579414450] 'process raft request' (duration: 303.328317ms)","trace[579414450] 'compare' (duration: 18.259645ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:51.818069Z","caller":"traceutil/trace.go:171","msg":"trace[1978454809] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"252.450058ms","start":"2026-05-27T13:46:51.565571Z","end":"2026-05-27T13:46:51.818021Z","steps":["trace[1978454809] 'read index received' (duration: 102.600891ms)","trace[1978454809] 'applied index is now lower than readState.Index' (duration: 149.847377ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:51.818303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:51.496254Z","time spent":"321.964858ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:46:51.818344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.736943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:46:51.81853Z","caller":"traceutil/trace.go:171","msg":"trace[1009811651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"252.978237ms","start":"2026-05-27T13:46:51.565532Z","end":"2026-05-27T13:46:51.81851Z","steps":["trace[1009811651] 'agreement among raft nodes before linearized reading' (duration: 252.728384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:52.156306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.034811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:46:52.156418Z","caller":"traceutil/trace.go:171","msg":"trace[1793968026] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"233.550816ms","start":"2026-05-27T13:46:51.922847Z","end":"2026-05-27T13:46:52.156398Z","steps":["trace[1793968026] 'process raft request' (duration: 87.324603ms)","trace[1793968026] 'compare' (duration: 145.749306ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:52.700203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.753795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:46:52.700326Z","caller":"traceutil/trace.go:171","msg":"trace[1636736498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"133.937558ms","start":"2026-05-27T13:46:52.566367Z","end":"2026-05-27T13:46:52.700305Z","steps":["trace[1636736498] 'range keys from in-memory index tree' (duration: 133.627664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:52.700567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.540612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.188\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T13:46:52.70094Z","caller":"traceutil/trace.go:171","msg":"trace[258726702] range","detail":"{range_begin:/registry/masterleases/199.19.213.188; range_end:; response_count:1; response_revision:409; }","duration":"309.677075ms","start":"2026-05-27T13:46:52.390965Z","end":"2026-05-27T13:46:52.700642Z","steps":["trace[258726702] 'range keys from in-memory index tree' (duration: 309.40565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:52.70099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:52.390944Z","time spent":"310.03157ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.188\" "} {"level":"warn","ts":"2026-05-27T13:46:53.044213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.544691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:46:53.044333Z","caller":"traceutil/trace.go:171","msg":"trace[498507455] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"159.641393ms","start":"2026-05-27T13:46:52.884673Z","end":"2026-05-27T13:46:53.044315Z","steps":["trace[498507455] 'process raft request' (duration: 35.911799ms)","trace[498507455] 'compare' (duration: 123.405079ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:53.711393Z","caller":"traceutil/trace.go:171","msg":"trace[763960482] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"215.813466ms","start":"2026-05-27T13:46:53.495556Z","end":"2026-05-27T13:46:53.71137Z","steps":["trace[763960482] 'process raft request' (duration: 215.726665ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:53.711675Z","caller":"traceutil/trace.go:171","msg":"trace[88329173] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"216.491536ms","start":"2026-05-27T13:46:53.495171Z","end":"2026-05-27T13:46:53.711663Z","steps":["trace[88329173] 'process raft request' (duration: 120.758807ms)","trace[88329173] 'compare' (duration: 95.212561ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:55.176061Z","caller":"traceutil/trace.go:171","msg":"trace[1849616801] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"181.469755ms","start":"2026-05-27T13:46:54.994548Z","end":"2026-05-27T13:46:55.176018Z","steps":["trace[1849616801] 'process raft request' (duration: 181.301352ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:55.735194Z","caller":"traceutil/trace.go:171","msg":"trace[648599237] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"136.077526ms","start":"2026-05-27T13:46:55.599072Z","end":"2026-05-27T13:46:55.73515Z","steps":["trace[648599237] 'process raft request' (duration: 104.813215ms)","trace[648599237] 'compare' (duration: 30.888315ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:46:57.982657Z","caller":"traceutil/trace.go:171","msg":"trace[37394339] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"389.357577ms","start":"2026-05-27T13:46:57.593276Z","end":"2026-05-27T13:46:57.982634Z","steps":["trace[37394339] 'process raft request' (duration: 389.164674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:57.982811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:57.593227Z","time spent":"389.51049ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:46:58.079631Z","caller":"traceutil/trace.go:171","msg":"trace[350798984] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:436; }","duration":"328.694666ms","start":"2026-05-27T13:46:57.750908Z","end":"2026-05-27T13:46:58.079602Z","steps":["trace[350798984] 'read index received' (duration: 231.483498ms)","trace[350798984] 'applied index is now lower than readState.Index' (duration: 97.209828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:46:58.079879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.492602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-27T13:46:58.079907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.95807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T13:46:58.08003Z","caller":"traceutil/trace.go:171","msg":"trace[1363498131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:424; }","duration":"329.149323ms","start":"2026-05-27T13:46:57.75085Z","end":"2026-05-27T13:46:58.08Z","steps":["trace[1363498131] 'agreement among raft nodes before linearized reading' (duration: 328.883179ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:46:58.080044Z","caller":"traceutil/trace.go:171","msg":"trace[391732734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"328.586474ms","start":"2026-05-27T13:46:57.751329Z","end":"2026-05-27T13:46:58.079915Z","steps":["trace[391732734] 'agreement among raft nodes before linearized reading' (duration: 328.446072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:46:58.080096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:57.750828Z","time spent":"329.257825ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T13:46:58.080099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:46:57.751321Z","time spent":"328.765957ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T13:47:00.383948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.056829ms","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-27T13:47:00.384112Z","caller":"traceutil/trace.go:171","msg":"trace[991665558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:431; }","duration":"113.264103ms","start":"2026-05-27T13:47:00.270828Z","end":"2026-05-27T13:47:00.384093Z","steps":["trace[991665558] 'range keys from in-memory index tree' (duration: 112.947517ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:06.229494Z","caller":"traceutil/trace.go:171","msg":"trace[987297783] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"146.075631ms","start":"2026-05-27T13:47:06.083383Z","end":"2026-05-27T13:47:06.229459Z","steps":["trace[987297783] 'process raft request' (duration: 145.836916ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:06.630664Z","caller":"traceutil/trace.go:171","msg":"trace[151340409] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"178.842253ms","start":"2026-05-27T13:47:06.451791Z","end":"2026-05-27T13:47:06.630633Z","steps":["trace[151340409] 'process raft request' (duration: 178.67976ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:08.697009Z","caller":"traceutil/trace.go:171","msg":"trace[1457368114] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"455.280111ms","start":"2026-05-27T13:47:08.241707Z","end":"2026-05-27T13:47:08.696987Z","steps":["trace[1457368114] 'read index received' (duration: 455.139148ms)","trace[1457368114] 'applied index is now lower than readState.Index' (duration: 139.773µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:08.697122Z","caller":"traceutil/trace.go:171","msg":"trace[1137293172] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"459.461481ms","start":"2026-05-27T13:47:08.237637Z","end":"2026-05-27T13:47:08.697099Z","steps":["trace[1137293172] 'process raft request' (duration: 459.221126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:08.697193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.472145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-27T13:47:08.697268Z","caller":"traceutil/trace.go:171","msg":"trace[1730556358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:447; }","duration":"455.578468ms","start":"2026-05-27T13:47:08.241667Z","end":"2026-05-27T13:47:08.697246Z","steps":["trace[1730556358] 'agreement among raft nodes before linearized reading' (duration: 455.432675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:08.697261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:08.237617Z","time spent":"459.568184ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:47:08.697328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:08.241657Z","time spent":"455.655229ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T13:47:09.044918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.801432ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T13:47:09.045198Z","caller":"traceutil/trace.go:171","msg":"trace[876358004] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:462; }","duration":"348.106576ms","start":"2026-05-27T13:47:08.697073Z","end":"2026-05-27T13:47:09.04518Z","steps":["trace[876358004] 'read index received' (duration: 127.950207ms)","trace[876358004] 'applied index is now lower than readState.Index' (duration: 220.155319ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:09.045269Z","caller":"traceutil/trace.go:171","msg":"trace[897034778] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"397.170204ms","start":"2026-05-27T13:47:08.648088Z","end":"2026-05-27T13:47:09.045259Z","steps":["trace[897034778] 'process raft request' (duration: 396.925109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:09.045355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:08.648062Z","time spent":"397.232085ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:47:09.04539Z","caller":"traceutil/trace.go:171","msg":"trace[1490480833] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"343.513708ms","start":"2026-05-27T13:47:08.701813Z","end":"2026-05-27T13:47:09.045327Z","steps":["trace[1490480833] 'process raft request' (duration: 343.329964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:09.045557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:08.701789Z","time spent":"343.662661ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:47:09.045656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.897997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:47:09.045684Z","caller":"traceutil/trace.go:171","msg":"trace[31395647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:449; }","duration":"479.954627ms","start":"2026-05-27T13:47:08.56572Z","end":"2026-05-27T13:47:09.045675Z","steps":["trace[31395647] 'agreement among raft nodes before linearized reading' (duration: 479.919666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:09.045718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:08.565707Z","time spent":"480.006179ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T13:47:09.045736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.571804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-27T13:47:09.045797Z","caller":"traceutil/trace.go:171","msg":"trace[1975335010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:448; }","duration":"792.652446ms","start":"2026-05-27T13:47:08.253123Z","end":"2026-05-27T13:47:09.045775Z","steps":["trace[1975335010] 'agreement among raft nodes before linearized reading' (duration: 792.112965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:09.045838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:08.25311Z","time spent":"792.719697ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T13:47:09.04562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.306389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T13:47:09.046072Z","caller":"traceutil/trace.go:171","msg":"trace[1085993189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:449; }","duration":"411.791539ms","start":"2026-05-27T13:47:08.634263Z","end":"2026-05-27T13:47:09.046055Z","steps":["trace[1085993189] 'agreement among raft nodes before linearized reading' (duration: 411.306749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:09.046119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:08.634244Z","time spent":"411.86195ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T13:47:09.184437Z","caller":"traceutil/trace.go:171","msg":"trace[143587849] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"134.446175ms","start":"2026-05-27T13:47:09.049955Z","end":"2026-05-27T13:47:09.184402Z","steps":["trace[143587849] 'process raft request' (duration: 123.934625ms)","trace[143587849] 'compare' (duration: 10.398808ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:47:15.49951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.420758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-27T13:47:15.499572Z","caller":"traceutil/trace.go:171","msg":"trace[1132358764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"292.537961ms","start":"2026-05-27T13:47:15.20702Z","end":"2026-05-27T13:47:15.499558Z","steps":["trace[1132358764] 'range keys from in-memory index tree' (duration: 292.311776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:15.499613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.034173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-05-27T13:47:15.499808Z","caller":"traceutil/trace.go:171","msg":"trace[633916694] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:464; }","duration":"153.156216ms","start":"2026-05-27T13:47:15.34651Z","end":"2026-05-27T13:47:15.499666Z","steps":["trace[633916694] 'range keys from in-memory index tree' (duration: 152.784838ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:26.951166Z","caller":"traceutil/trace.go:171","msg":"trace[8441321] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"128.548405ms","start":"2026-05-27T13:47:26.822599Z","end":"2026-05-27T13:47:26.951148Z","steps":["trace[8441321] 'read index received' (duration: 128.159905ms)","trace[8441321] 'applied index is now lower than readState.Index' (duration: 387.5µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:26.951359Z","caller":"traceutil/trace.go:171","msg":"trace[1544708579] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"152.613381ms","start":"2026-05-27T13:47:26.798618Z","end":"2026-05-27T13:47:26.951232Z","steps":["trace[1544708579] 'process raft request' (duration: 152.275952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:26.951417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.802983ms","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-27T13:47:26.951523Z","caller":"traceutil/trace.go:171","msg":"trace[1162004152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"128.962137ms","start":"2026-05-27T13:47:26.822536Z","end":"2026-05-27T13:47:26.951498Z","steps":["trace[1162004152] 'agreement among raft nodes before linearized reading' (duration: 128.71256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:27.22559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.091467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:47:27.225817Z","caller":"traceutil/trace.go:171","msg":"trace[1308890447] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"269.436756ms","start":"2026-05-27T13:47:26.956354Z","end":"2026-05-27T13:47:27.225791Z","steps":["trace[1308890447] 'process raft request' (duration: 78.061851ms)","trace[1308890447] 'compare' (duration: 190.942303ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:27.225894Z","caller":"traceutil/trace.go:171","msg":"trace[1503672179] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"254.688965ms","start":"2026-05-27T13:47:26.97114Z","end":"2026-05-27T13:47:27.225829Z","steps":["trace[1503672179] 'process raft request' (duration: 254.558792ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:27.225961Z","caller":"traceutil/trace.go:171","msg":"trace[714092040] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"253.77016ms","start":"2026-05-27T13:47:26.97217Z","end":"2026-05-27T13:47:27.22594Z","steps":["trace[714092040] 'process raft request' (duration: 253.581605ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:27.437067Z","caller":"traceutil/trace.go:171","msg":"trace[1342986766] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"124.983132ms","start":"2026-05-27T13:47:27.312058Z","end":"2026-05-27T13:47:27.437041Z","steps":["trace[1342986766] 'read index received' (duration: 67.204455ms)","trace[1342986766] 'applied index is now lower than readState.Index' (duration: 57.777587ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:27.437161Z","caller":"traceutil/trace.go:171","msg":"trace[1532003049] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"205.74915ms","start":"2026-05-27T13:47:27.231374Z","end":"2026-05-27T13:47:27.437123Z","steps":["trace[1532003049] 'process raft request' (duration: 147.876801ms)","trace[1532003049] 'compare' (duration: 57.658233ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:47:27.437532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.463694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T13:47:27.437594Z","caller":"traceutil/trace.go:171","msg":"trace[1825738384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"125.597548ms","start":"2026-05-27T13:47:27.311979Z","end":"2026-05-27T13:47:27.437576Z","steps":["trace[1825738384] 'agreement among raft nodes before linearized reading' (duration: 125.174957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:29.809356Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592759594988341423,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T13:47:29.813073Z","caller":"traceutil/trace.go:171","msg":"trace[586787143] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"578.586542ms","start":"2026-05-27T13:47:29.234461Z","end":"2026-05-27T13:47:29.813047Z","steps":["trace[586787143] 'process raft request' (duration: 578.374986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:29.813231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:29.234428Z","time spent":"578.721175ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:47:30.32294Z","caller":"traceutil/trace.go:171","msg":"trace[1738259658] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:575; }","duration":"1.014657458s","start":"2026-05-27T13:47:29.308258Z","end":"2026-05-27T13:47:30.322916Z","steps":["trace[1738259658] 'read index received' (duration: 504.585691ms)","trace[1738259658] 'applied index is now lower than readState.Index' (duration: 510.070487ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:30.32317Z","caller":"traceutil/trace.go:171","msg":"trace[796244948] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"737.730359ms","start":"2026-05-27T13:47:29.585431Z","end":"2026-05-27T13:47:30.323162Z","steps":["trace[796244948] 'process raft request' (duration: 737.372588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:30.323282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:29.585396Z","time spent":"737.805391ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:47:30.323294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.143304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-27T13:47:30.323296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.929407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:47:30.323361Z","caller":"traceutil/trace.go:171","msg":"trace[1979752694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"870.300909ms","start":"2026-05-27T13:47:29.453046Z","end":"2026-05-27T13:47:30.323347Z","steps":["trace[1979752694] 'agreement among raft nodes before linearized reading' (duration: 870.122794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:30.323359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.923088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-27T13:47:30.323377Z","caller":"traceutil/trace.go:171","msg":"trace[430808472] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:555; }","duration":"553.065951ms","start":"2026-05-27T13:47:29.770292Z","end":"2026-05-27T13:47:30.323358Z","steps":["trace[430808472] 'agreement among raft nodes before linearized reading' (duration: 552.909987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:30.3234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:29.45302Z","time spent":"870.373181ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T13:47:30.323432Z","caller":"traceutil/trace.go:171","msg":"trace[1526810889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"711.041922ms","start":"2026-05-27T13:47:29.612371Z","end":"2026-05-27T13:47:30.323413Z","steps":["trace[1526810889] 'agreement among raft nodes before linearized reading' (duration: 710.806965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:30.323435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:29.770258Z","time spent":"553.168324ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-27T13:47:30.323479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:29.612355Z","time spent":"711.115933ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T13:47:30.323779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.291674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:47:30.323884Z","caller":"traceutil/trace.go:171","msg":"trace[1436156702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"758.398417ms","start":"2026-05-27T13:47:29.56541Z","end":"2026-05-27T13:47:30.323809Z","steps":["trace[1436156702] 'agreement among raft nodes before linearized reading' (duration: 758.295554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:30.323967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:29.56539Z","time spent":"758.525421ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T13:47:30.323981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015722669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-27T13:47:30.324032Z","caller":"traceutil/trace.go:171","msg":"trace[921008478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"1.015819672s","start":"2026-05-27T13:47:29.308199Z","end":"2026-05-27T13:47:30.324018Z","steps":["trace[921008478] 'agreement among raft nodes before linearized reading' (duration: 1.014911306s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:30.324062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:29.308176Z","time spent":"1.015880633s","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-27T13:47:30.603742Z","caller":"traceutil/trace.go:171","msg":"trace[221809876] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"128.939151ms","start":"2026-05-27T13:47:30.474745Z","end":"2026-05-27T13:47:30.603684Z","steps":["trace[221809876] 'process raft request' (duration: 92.595285ms)","trace[221809876] 'compare' (duration: 36.010047ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:30.682357Z","caller":"traceutil/trace.go:171","msg":"trace[451476676] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"112.863529ms","start":"2026-05-27T13:47:30.569467Z","end":"2026-05-27T13:47:30.682331Z","steps":["trace[451476676] 'process raft request' (duration: 112.247511ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:30.910823Z","caller":"traceutil/trace.go:171","msg":"trace[1456434713] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"105.421437ms","start":"2026-05-27T13:47:30.805356Z","end":"2026-05-27T13:47:30.910777Z","steps":["trace[1456434713] 'process raft request' (duration: 40.885964ms)","trace[1456434713] 'compare' (duration: 64.329647ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:31.979634Z","caller":"traceutil/trace.go:171","msg":"trace[1737776381] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"279.890876ms","start":"2026-05-27T13:47:31.699711Z","end":"2026-05-27T13:47:31.979602Z","steps":["trace[1737776381] 'process raft request' (duration: 263.987922ms)","trace[1737776381] 'compare' (duration: 15.756849ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:32.938197Z","caller":"traceutil/trace.go:171","msg":"trace[1549800973] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.19345967s","start":"2026-05-27T13:47:31.744663Z","end":"2026-05-27T13:47:32.938123Z","steps":["trace[1549800973] 'process raft request' (duration: 1.190768413s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:32.938501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:31.744629Z","time spent":"1.193684167s","remote":"127.0.0.1:34108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:47:32.939947Z","caller":"traceutil/trace.go:171","msg":"trace[2125308430] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"1.122034726s","start":"2026-05-27T13:47:31.817882Z","end":"2026-05-27T13:47:32.939916Z","steps":["trace[2125308430] 'read index received' (duration: 145.712362ms)","trace[2125308430] 'applied index is now lower than readState.Index' (duration: 976.321404ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:32.940104Z","caller":"traceutil/trace.go:171","msg":"trace[1749123983] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.075372157s","start":"2026-05-27T13:47:31.864703Z","end":"2026-05-27T13:47:32.940075Z","steps":["trace[1749123983] 'process raft request' (duration: 1.073823992s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:32.940233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122319904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-27T13:47:32.940289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:31.864667Z","time spent":"1.075519631s","remote":"127.0.0.1:34108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:47:32.940324Z","caller":"traceutil/trace.go:171","msg":"trace[1412336967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"1.122450318s","start":"2026-05-27T13:47:31.817851Z","end":"2026-05-27T13:47:32.940302Z","steps":["trace[1412336967] 'agreement among raft nodes before linearized reading' (duration: 1.122237802s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:32.940367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:31.817837Z","time spent":"1.12252226s","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T13:47:32.94566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.365224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T13:47:32.945727Z","caller":"traceutil/trace.go:171","msg":"trace[1315082813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"610.480628ms","start":"2026-05-27T13:47:32.335229Z","end":"2026-05-27T13:47:32.94571Z","steps":["trace[1315082813] 'agreement among raft nodes before linearized reading' (duration: 610.353564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:32.945769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:32.335179Z","time spent":"610.581291ms","remote":"127.0.0.1:34320","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-27T13:47:32.945718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.459379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:47:32.945837Z","caller":"traceutil/trace.go:171","msg":"trace[1823801153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"378.637904ms","start":"2026-05-27T13:47:32.567178Z","end":"2026-05-27T13:47:32.945816Z","steps":["trace[1823801153] 'agreement among raft nodes before linearized reading' (duration: 378.472579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:32.945877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:32.567162Z","time spent":"378.706165ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T13:47:32.945861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.578334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-27T13:47:32.945928Z","caller":"traceutil/trace.go:171","msg":"trace[932093036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"607.680277ms","start":"2026-05-27T13:47:32.338232Z","end":"2026-05-27T13:47:32.945912Z","steps":["trace[932093036] 'agreement among raft nodes before linearized reading' (duration: 607.559044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:32.945962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.77585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-27T13:47:32.945965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:32.338211Z","time spent":"607.74615ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T13:47:32.945996Z","caller":"traceutil/trace.go:171","msg":"trace[455553039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"608.848371ms","start":"2026-05-27T13:47:32.337135Z","end":"2026-05-27T13:47:32.945984Z","steps":["trace[455553039] 'agreement among raft nodes before linearized reading' (duration: 608.79805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:32.94603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:32.337098Z","time spent":"608.925623ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-27T13:47:32.946023Z","caller":"traceutil/trace.go:171","msg":"trace[2014080592] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"936.662854ms","start":"2026-05-27T13:47:32.009337Z","end":"2026-05-27T13:47:32.946Z","steps":["trace[2014080592] 'process raft request' (duration: 936.101008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:32.946121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.915614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.188\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-27T13:47:32.946129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:32.009315Z","time spent":"936.755707ms","remote":"127.0.0.1:34108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:47:32.946152Z","caller":"traceutil/trace.go:171","msg":"trace[1964821986] range","detail":"{range_begin:/registry/masterleases/199.19.213.188; range_end:; response_count:1; response_revision:627; }","duration":"552.985346ms","start":"2026-05-27T13:47:32.393158Z","end":"2026-05-27T13:47:32.946143Z","steps":["trace[1964821986] 'agreement among raft nodes before linearized reading' (duration: 552.929394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:32.946177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:32.393132Z","time spent":"553.039518ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.188\" "} {"level":"warn","ts":"2026-05-27T13:47:33.835712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592759594988341534,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T13:47:33.897345Z","caller":"traceutil/trace.go:171","msg":"trace[1161403113] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"568.098977ms","start":"2026-05-27T13:47:33.329197Z","end":"2026-05-27T13:47:33.897296Z","steps":["trace[1161403113] 'read index received' (duration: 506.659916ms)","trace[1161403113] 'applied index is now lower than readState.Index' (duration: 61.437871ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:33.897616Z","caller":"traceutil/trace.go:171","msg":"trace[1594082792] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"585.688259ms","start":"2026-05-27T13:47:33.311858Z","end":"2026-05-27T13:47:33.897546Z","steps":["trace[1594082792] 'process raft request' (duration: 524.585338ms)","trace[1594082792] 'compare' (duration: 60.231416ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:47:33.89768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.46443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-05-27T13:47:33.897727Z","caller":"traceutil/trace.go:171","msg":"trace[2033820010] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:639; }","duration":"568.551361ms","start":"2026-05-27T13:47:33.329164Z","end":"2026-05-27T13:47:33.897716Z","steps":["trace[2033820010] 'agreement among raft nodes before linearized reading' (duration: 568.253792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:33.897763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:33.32915Z","time spent":"568.597413ms","remote":"127.0.0.1:34108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":80726,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-27T13:47:33.897941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:33.311839Z","time spent":"585.877504ms","remote":"127.0.0.1:34108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:47:33.938381Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":1592759594988341537,"received-request-id":1592759594988341534} {"level":"warn","ts":"2026-05-27T13:47:40.065449Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592759594988341629,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T13:47:40.566701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592759594988341629,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T13:47:40.692345Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.27221252s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T13:47:40.697899Z","caller":"traceutil/trace.go:171","msg":"trace[586951972] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"1.279679508s","start":"2026-05-27T13:47:39.418182Z","end":"2026-05-27T13:47:40.697862Z","steps":["trace[586951972] 'process raft request' (duration: 1.274542431s)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:40.697893Z","caller":"traceutil/trace.go:171","msg":"trace[1222942026] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"1.132822907s","start":"2026-05-27T13:47:39.565008Z","end":"2026-05-27T13:47:40.697831Z","steps":["trace[1222942026] 'read index received' (duration: 1.127468362s)","trace[1222942026] 'applied index is now lower than readState.Index' (duration: 5.353195ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:47:40.698007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132984571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:47:40.69806Z","caller":"traceutil/trace.go:171","msg":"trace[805605127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"1.133084635s","start":"2026-05-27T13:47:39.564959Z","end":"2026-05-27T13:47:40.698044Z","steps":["trace[805605127] 'agreement among raft nodes before linearized reading' (duration: 1.132990622s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:40.6981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:39.564938Z","time spent":"1.133153986s","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T13:47:40.698116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040495971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vf7v8\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-27T13:47:40.69815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.047531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:47:40.698171Z","caller":"traceutil/trace.go:171","msg":"trace[279347840] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vf7v8; range_end:; response_count:1; response_revision:673; }","duration":"1.040601324s","start":"2026-05-27T13:47:39.657558Z","end":"2026-05-27T13:47:40.698159Z","steps":["trace[279347840] 'agreement among raft nodes before linearized reading' (duration: 1.040482341s)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:40.698199Z","caller":"traceutil/trace.go:171","msg":"trace[258413477] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:673; }","duration":"567.127223ms","start":"2026-05-27T13:47:40.131055Z","end":"2026-05-27T13:47:40.698182Z","steps":["trace[258413477] 'agreement among raft nodes before linearized reading' (duration: 567.046221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:40.6982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.839646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-27T13:47:40.698208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:39.657541Z","time spent":"1.040659517s","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-vf7v8\" "} {"level":"warn","ts":"2026-05-27T13:47:40.698234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:40.131037Z","time spent":"567.186674ms","remote":"127.0.0.1:34566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-27T13:47:40.698235Z","caller":"traceutil/trace.go:171","msg":"trace[707077079] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:673; }","duration":"863.934988ms","start":"2026-05-27T13:47:39.83429Z","end":"2026-05-27T13:47:40.698225Z","steps":["trace[707077079] 'agreement among raft nodes before linearized reading' (duration: 863.839176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:40.698068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:39.418136Z","time spent":"1.279833853s","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:47:40.698302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:39.834271Z","time spent":"864.01886ms","remote":"127.0.0.1:34266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-05-27T13:47:41.439184Z","caller":"traceutil/trace.go:171","msg":"trace[693147583] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"723.631777ms","start":"2026-05-27T13:47:40.715528Z","end":"2026-05-27T13:47:41.43916Z","steps":["trace[693147583] 'process raft request' (duration: 723.440332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:41.439354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:40.71551Z","time spent":"723.768511ms","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:47:41.932467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.694231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:47:41.932618Z","caller":"traceutil/trace.go:171","msg":"trace[1193184212] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:703; }","duration":"887.409325ms","start":"2026-05-27T13:47:41.045196Z","end":"2026-05-27T13:47:41.932605Z","steps":["trace[1193184212] 'read index received' (duration: 393.753913ms)","trace[1193184212] 'applied index is now lower than readState.Index' (duration: 493.654442ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:41.932651Z","caller":"traceutil/trace.go:171","msg":"trace[2026433353] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"1.178951615s","start":"2026-05-27T13:47:40.753674Z","end":"2026-05-27T13:47:41.932625Z","steps":["trace[2026433353] 'process raft request' (duration: 1.178891934s)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:41.932694Z","caller":"traceutil/trace.go:171","msg":"trace[443650693] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"1.216277097s","start":"2026-05-27T13:47:40.716359Z","end":"2026-05-27T13:47:41.932636Z","steps":["trace[443650693] 'process raft request' (duration: 994.334368ms)","trace[443650693] 'compare' (duration: 221.493325ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:47:41.932764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:40.753655Z","time spent":"1.179067858s","remote":"127.0.0.1:34308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:47:41.932827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.963899ms","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-27T13:47:41.932855Z","caller":"traceutil/trace.go:171","msg":"trace[299156410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"846.009479ms","start":"2026-05-27T13:47:41.086837Z","end":"2026-05-27T13:47:41.932847Z","steps":["trace[299156410] 'agreement among raft nodes before linearized reading' (duration: 845.948967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:41.932878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:41.086827Z","time spent":"846.046541ms","remote":"127.0.0.1:34320","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-27T13:47:41.932931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:40.716346Z","time spent":"1.216465283s","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:47:41.932945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.328801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T13:47:41.93302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.73306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-27T13:47:41.933041Z","caller":"traceutil/trace.go:171","msg":"trace[2117777688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:676; }","duration":"844.803683ms","start":"2026-05-27T13:47:41.088231Z","end":"2026-05-27T13:47:41.933035Z","steps":["trace[2117777688] 'agreement among raft nodes before linearized reading' (duration: 844.748131ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:41.933042Z","caller":"traceutil/trace.go:171","msg":"trace[6645323] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:676; }","duration":"492.514177ms","start":"2026-05-27T13:47:41.440509Z","end":"2026-05-27T13:47:41.933023Z","steps":["trace[6645323] 'agreement among raft nodes before linearized reading' (duration: 492.338641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:41.932764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.578021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-27T13:47:41.933107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.059211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-05-27T13:47:41.933099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:41.440482Z","time spent":"492.60694ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-05-27T13:47:41.933123Z","caller":"traceutil/trace.go:171","msg":"trace[1780081599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:676; }","duration":"846.129114ms","start":"2026-05-27T13:47:41.086989Z","end":"2026-05-27T13:47:41.933119Z","steps":["trace[1780081599] 'agreement among raft nodes before linearized reading' (duration: 846.079632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:41.93314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:41.086946Z","time spent":"846.189545ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T13:47:41.933123Z","caller":"traceutil/trace.go:171","msg":"trace[552567270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"887.967282ms","start":"2026-05-27T13:47:41.045144Z","end":"2026-05-27T13:47:41.933111Z","steps":["trace[552567270] 'agreement among raft nodes before linearized reading' (duration: 887.526309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:41.933172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:41.045125Z","time spent":"888.036715ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T13:47:41.933058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:41.088202Z","time spent":"844.851955ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T13:47:44.573045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000132572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-27T13:47:44.573136Z","caller":"traceutil/trace.go:171","msg":"trace[1927623075] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000274656s","start":"2026-05-27T13:47:42.57284Z","end":"2026-05-27T13:47:44.573115Z","steps":["trace[1927623075] 'agreement among raft nodes before linearized reading' (duration: 2.000159813s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:44.573195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:42.572821Z","time spent":"2.00036302s","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T13:47:44.775529Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.706139101s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-27T13:47:44.989197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.919744007s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:47:44.989366Z","caller":"traceutil/trace.go:171","msg":"trace[195174917] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"3.050721138s","start":"2026-05-27T13:47:41.938629Z","end":"2026-05-27T13:47:44.98935Z","steps":["trace[195174917] 'process raft request' (duration: 130.764174ms)","trace[195174917] 'compare' (duration: 2.919549451s)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:47:44.989425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:41.938616Z","time spent":"3.05078502s","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:47:44.989513Z","caller":"traceutil/trace.go:171","msg":"trace[2134970822] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:706; }","duration":"3.046925821s","start":"2026-05-27T13:47:41.942575Z","end":"2026-05-27T13:47:44.989501Z","steps":["trace[2134970822] 'read index received' (duration: 126.836293ms)","trace[2134970822] 'applied index is now lower than readState.Index' (duration: 2.920088668s)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:44.98958Z","caller":"traceutil/trace.go:171","msg":"trace[2126084940] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"3.05046236s","start":"2026-05-27T13:47:41.939113Z","end":"2026-05-27T13:47:44.989575Z","steps":["trace[2126084940] 'process raft request' (duration: 3.050328126s)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:44.989597Z","caller":"traceutil/trace.go:171","msg":"trace[1162083317] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"3.049559172s","start":"2026-05-27T13:47:41.940029Z","end":"2026-05-27T13:47:44.989588Z","steps":["trace[1162083317] 'process raft request' (duration: 3.049440809s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:44.989617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:41.93908Z","time spent":"3.050512712s","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:47:44.989649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:41.940017Z","time spent":"3.049600113s","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:47:44.989735Z","caller":"traceutil/trace.go:171","msg":"trace[630830661] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"3.050797471s","start":"2026-05-27T13:47:41.93893Z","end":"2026-05-27T13:47:44.989728Z","steps":["trace[630830661] 'process raft request' (duration: 3.0504596s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:44.989765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:41.938922Z","time spent":"3.050827111s","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:47:44.989812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.047235351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-27T13:47:44.989839Z","caller":"traceutil/trace.go:171","msg":"trace[174272487] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:680; }","duration":"3.047277632s","start":"2026-05-27T13:47:41.942553Z","end":"2026-05-27T13:47:44.989831Z","steps":["trace[174272487] 'agreement among raft nodes before linearized reading' (duration: 3.04722563s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:44.98987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:41.942546Z","time spent":"3.047318243s","remote":"127.0.0.1:34266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-27T13:47:44.990082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.596122067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.188\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T13:47:44.990159Z","caller":"traceutil/trace.go:171","msg":"trace[113126560] range","detail":"{range_begin:/registry/masterleases/199.19.213.188; range_end:; response_count:1; response_revision:680; }","duration":"2.59621173s","start":"2026-05-27T13:47:42.39393Z","end":"2026-05-27T13:47:44.990142Z","steps":["trace[113126560] 'agreement among raft nodes before linearized reading' (duration: 2.596058935s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:44.990194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.611539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T13:47:44.990198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:42.393865Z","time spent":"2.596324573s","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.188\" "} {"level":"info","ts":"2026-05-27T13:47:44.990213Z","caller":"traceutil/trace.go:171","msg":"trace[33462212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"416.655641ms","start":"2026-05-27T13:47:44.573551Z","end":"2026-05-27T13:47:44.990207Z","steps":["trace[33462212] 'agreement among raft nodes before linearized reading' (duration: 416.62509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:44.990227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:44.573537Z","time spent":"416.686881ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T13:47:44.990417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593095454s","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-27T13:47:44.990456Z","caller":"traceutil/trace.go:171","msg":"trace[1156722166] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:680; }","duration":"1.593177006s","start":"2026-05-27T13:47:43.397267Z","end":"2026-05-27T13:47:44.990444Z","steps":["trace[1156722166] 'agreement among raft nodes before linearized reading' (duration: 1.593109244s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:44.990512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:47:43.397234Z","time spent":"1.593265009s","remote":"127.0.0.1:34500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-05-27T13:47:47.213221Z","caller":"traceutil/trace.go:171","msg":"trace[1653605826] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"219.038266ms","start":"2026-05-27T13:47:46.994163Z","end":"2026-05-27T13:47:47.213201Z","steps":["trace[1653605826] 'read index received' (duration: 218.951113ms)","trace[1653605826] 'applied index is now lower than readState.Index' (duration: 85.983µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:47.21332Z","caller":"traceutil/trace.go:171","msg":"trace[376235704] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"287.378252ms","start":"2026-05-27T13:47:46.925933Z","end":"2026-05-27T13:47:47.213311Z","steps":["trace[376235704] 'process raft request' (duration: 287.155205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:47.213834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.648675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-27T13:47:47.213937Z","caller":"traceutil/trace.go:171","msg":"trace[1072434751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:711; }","duration":"219.766749ms","start":"2026-05-27T13:47:46.994131Z","end":"2026-05-27T13:47:47.213897Z","steps":["trace[1072434751] 'agreement among raft nodes before linearized reading' (duration: 219.247052ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:47.232141Z","caller":"traceutil/trace.go:171","msg":"trace[425032359] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"104.512578ms","start":"2026-05-27T13:47:47.127603Z","end":"2026-05-27T13:47:47.232116Z","steps":["trace[425032359] 'process raft request' (duration: 104.379854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:47.232169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.93918ms","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-27T13:47:47.232254Z","caller":"traceutil/trace.go:171","msg":"trace[181589933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:713; }","duration":"238.020433ms","start":"2026-05-27T13:47:46.994193Z","end":"2026-05-27T13:47:47.232213Z","steps":["trace[181589933] 'agreement among raft nodes before linearized reading' (duration: 237.841178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:47.232282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.749796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-27T13:47:47.232326Z","caller":"traceutil/trace.go:171","msg":"trace[1495471009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:713; }","duration":"237.835598ms","start":"2026-05-27T13:47:46.994479Z","end":"2026-05-27T13:47:47.232314Z","steps":["trace[1495471009] 'agreement among raft nodes before linearized reading' (duration: 237.688273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:47.232388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.109417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-27T13:47:47.232423Z","caller":"traceutil/trace.go:171","msg":"trace[1958539958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:713; }","duration":"238.173769ms","start":"2026-05-27T13:47:46.994242Z","end":"2026-05-27T13:47:47.232415Z","steps":["trace[1958539958] 'agreement among raft nodes before linearized reading' (duration: 238.088416ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:47.232793Z","caller":"traceutil/trace.go:171","msg":"trace[152640613] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"139.434998ms","start":"2026-05-27T13:47:47.093046Z","end":"2026-05-27T13:47:47.232481Z","steps":["trace[152640613] 'process raft request' (duration: 138.444285ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:50.060154Z","caller":"traceutil/trace.go:171","msg":"trace[325095433] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"107.198215ms","start":"2026-05-27T13:47:49.952924Z","end":"2026-05-27T13:47:50.060122Z","steps":["trace[325095433] 'process raft request' (duration: 106.953697ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:50.737453Z","caller":"traceutil/trace.go:171","msg":"trace[1778867508] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"107.29607ms","start":"2026-05-27T13:47:50.630122Z","end":"2026-05-27T13:47:50.737418Z","steps":["trace[1778867508] 'process raft request' (duration: 106.806814ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:51.519941Z","caller":"traceutil/trace.go:171","msg":"trace[1362359721] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"182.476294ms","start":"2026-05-27T13:47:51.337441Z","end":"2026-05-27T13:47:51.519917Z","steps":["trace[1362359721] 'process raft request' (duration: 182.04769ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:51.520256Z","caller":"traceutil/trace.go:171","msg":"trace[2081951901] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"182.373831ms","start":"2026-05-27T13:47:51.337866Z","end":"2026-05-27T13:47:51.52024Z","steps":["trace[2081951901] 'process raft request' (duration: 182.009139ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:51.520288Z","caller":"traceutil/trace.go:171","msg":"trace[336206616] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"182.293688ms","start":"2026-05-27T13:47:51.337974Z","end":"2026-05-27T13:47:51.520268Z","steps":["trace[336206616] 'process raft request' (duration: 182.122322ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:51.671517Z","caller":"traceutil/trace.go:171","msg":"trace[745664530] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"117.757212ms","start":"2026-05-27T13:47:51.553736Z","end":"2026-05-27T13:47:51.671493Z","steps":["trace[745664530] 'read index received' (duration: 90.198948ms)","trace[745664530] 'applied index is now lower than readState.Index' (duration: 27.557294ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:47:51.671954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.152825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T13:47:51.671967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.203646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:47:51.672091Z","caller":"traceutil/trace.go:171","msg":"trace[1045701056] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:771; }","duration":"118.370732ms","start":"2026-05-27T13:47:51.553701Z","end":"2026-05-27T13:47:51.672071Z","steps":["trace[1045701056] 'agreement among raft nodes before linearized reading' (duration: 118.167695ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:51.672734Z","caller":"traceutil/trace.go:171","msg":"trace[478447209] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"125.697923ms","start":"2026-05-27T13:47:51.547014Z","end":"2026-05-27T13:47:51.672712Z","steps":["trace[478447209] 'process raft request' (duration: 96.937469ms)","trace[478447209] 'compare' (duration: 27.216343ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:47:51.672004Z","caller":"traceutil/trace.go:171","msg":"trace[723093060] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:771; }","duration":"118.245798ms","start":"2026-05-27T13:47:51.553745Z","end":"2026-05-27T13:47:51.67199Z","steps":["trace[723093060] 'agreement among raft nodes before linearized reading' (duration: 118.156785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:47:51.674359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.641575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:47:51.67444Z","caller":"traceutil/trace.go:171","msg":"trace[877669057] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:771; }","duration":"119.714417ms","start":"2026-05-27T13:47:51.554687Z","end":"2026-05-27T13:47:51.674402Z","steps":["trace[877669057] 'agreement among raft nodes before linearized reading' (duration: 119.634484ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:47:51.914385Z","caller":"traceutil/trace.go:171","msg":"trace[1350632937] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"171.260536ms","start":"2026-05-27T13:47:51.7431Z","end":"2026-05-27T13:47:51.91436Z","steps":["trace[1350632937] 'process raft request' (duration: 148.459859ms)","trace[1350632937] 'compare' (duration: 22.644012ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:48:01.599452Z","caller":"traceutil/trace.go:171","msg":"trace[2010584632] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:870; }","duration":"197.628326ms","start":"2026-05-27T13:48:01.401801Z","end":"2026-05-27T13:48:01.59943Z","steps":["trace[2010584632] 'read index received' (duration: 179.943636ms)","trace[2010584632] 'applied index is now lower than readState.Index' (duration: 17.68317ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:48:01.59973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.907315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T13:48:01.600668Z","caller":"traceutil/trace.go:171","msg":"trace[385504165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:836; }","duration":"198.084251ms","start":"2026-05-27T13:48:01.401764Z","end":"2026-05-27T13:48:01.599849Z","steps":["trace[385504165] 'agreement among raft nodes before linearized reading' (duration: 197.75228ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:01.600842Z","caller":"traceutil/trace.go:171","msg":"trace[718752007] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"201.484659ms","start":"2026-05-27T13:48:01.399235Z","end":"2026-05-27T13:48:01.60072Z","steps":["trace[718752007] 'process raft request' (duration: 182.628428ms)","trace[718752007] 'compare' (duration: 17.327568ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:48:02.264599Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592759594988342009,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T13:48:02.76542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592759594988342009,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T13:48:03.265725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592759594988342009,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T13:48:03.387031Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.781649727s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T13:48:03.396259Z","caller":"traceutil/trace.go:171","msg":"trace[710449833] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"1.790959911s","start":"2026-05-27T13:48:01.605279Z","end":"2026-05-27T13:48:03.396239Z","steps":["trace[710449833] 'process raft request' (duration: 1.781942987s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:48:03.396512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:48:01.605253Z","time spent":"1.791088176s","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:48:03.401459Z","caller":"traceutil/trace.go:171","msg":"trace[1435487014] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"1.796045678s","start":"2026-05-27T13:48:01.605395Z","end":"2026-05-27T13:48:03.401441Z","steps":["trace[1435487014] 'process raft request' (duration: 1.795856602s)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:03.401502Z","caller":"traceutil/trace.go:171","msg":"trace[1815080975] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:871; }","duration":"1.637703367s","start":"2026-05-27T13:48:01.763783Z","end":"2026-05-27T13:48:03.401487Z","steps":["trace[1815080975] 'read index received' (duration: 1.623498972s)","trace[1815080975] 'applied index is now lower than readState.Index' (duration: 14.203595ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:48:03.401527Z","caller":"traceutil/trace.go:171","msg":"trace[1135494246] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"1.795445338s","start":"2026-05-27T13:48:01.606072Z","end":"2026-05-27T13:48:03.401517Z","steps":["trace[1135494246] 'process raft request' (duration: 1.795278312s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:48:03.401666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.637882992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dq7xk\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-05-27T13:48:03.401657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:48:01.605377Z","time spent":"1.79612602s","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:48:03.401707Z","caller":"traceutil/trace.go:171","msg":"trace[584983038] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dq7xk; range_end:; response_count:1; response_revision:840; }","duration":"1.637975716s","start":"2026-05-27T13:48:01.763718Z","end":"2026-05-27T13:48:03.401694Z","steps":["trace[584983038] 'agreement among raft nodes before linearized reading' (duration: 1.637860672s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:48:03.401738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:48:01.763693Z","time spent":"1.638037878s","remote":"127.0.0.1:34246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dq7xk\" "} {"level":"warn","ts":"2026-05-27T13:48:03.401807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:48:01.606041Z","time spent":"1.795646175s","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:48:03.401558Z","caller":"traceutil/trace.go:171","msg":"trace[824445828] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"858.672847ms","start":"2026-05-27T13:48:02.54288Z","end":"2026-05-27T13:48:03.401553Z","steps":["trace[824445828] 'process raft request' (duration: 858.531722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:48:03.401932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:48:02.542847Z","time spent":"859.04628ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:48:03.401964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.551306637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T13:48:03.402028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.496979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T13:48:03.402037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.974743ms","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-27T13:48:03.40206Z","caller":"traceutil/trace.go:171","msg":"trace[422833235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:840; }","duration":"836.565222ms","start":"2026-05-27T13:48:02.565486Z","end":"2026-05-27T13:48:03.402051Z","steps":["trace[422833235] 'agreement among raft nodes before linearized reading' (duration: 836.517921ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:03.402069Z","caller":"traceutil/trace.go:171","msg":"trace[629409989] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:840; }","duration":"774.049196ms","start":"2026-05-27T13:48:02.628011Z","end":"2026-05-27T13:48:03.40206Z","steps":["trace[629409989] 'agreement among raft nodes before linearized reading' (duration: 773.979004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:48:03.402085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:48:02.565466Z","time spent":"836.615513ms","remote":"127.0.0.1:34052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T13:48:03.402097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:48:02.627964Z","time spent":"774.126339ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T13:48:03.402214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006716526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.188\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T13:48:03.402248Z","caller":"traceutil/trace.go:171","msg":"trace[1695020746] range","detail":"{range_begin:/registry/masterleases/199.19.213.188; range_end:; response_count:1; response_revision:840; }","duration":"1.00680835s","start":"2026-05-27T13:48:02.395431Z","end":"2026-05-27T13:48:03.402239Z","steps":["trace[1695020746] 'agreement among raft nodes before linearized reading' (duration: 1.006731437s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:48:03.402278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:48:02.39541Z","time spent":"1.006855521s","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.188\" "} {"level":"info","ts":"2026-05-27T13:48:03.402364Z","caller":"traceutil/trace.go:171","msg":"trace[317193932] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:840; }","duration":"1.551704011s","start":"2026-05-27T13:48:01.850559Z","end":"2026-05-27T13:48:03.402263Z","steps":["trace[317193932] 'agreement among raft nodes before linearized reading' (duration: 1.551331988s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:48:03.402483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:48:01.850538Z","time spent":"1.551859137s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-05-27T13:48:06.908311Z","caller":"traceutil/trace.go:171","msg":"trace[398755925] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"104.858514ms","start":"2026-05-27T13:48:06.803437Z","end":"2026-05-27T13:48:06.908295Z","steps":["trace[398755925] 'process raft request' (duration: 104.713839ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:07.840274Z","caller":"traceutil/trace.go:171","msg":"trace[1180488499] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"109.012697ms","start":"2026-05-27T13:48:07.731236Z","end":"2026-05-27T13:48:07.840248Z","steps":["trace[1180488499] 'process raft request' (duration: 108.320963ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:07.863758Z","caller":"traceutil/trace.go:171","msg":"trace[1827561089] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"120.251764ms","start":"2026-05-27T13:48:07.74348Z","end":"2026-05-27T13:48:07.863732Z","steps":["trace[1827561089] 'process raft request' (duration: 119.984695ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:07.903453Z","caller":"traceutil/trace.go:171","msg":"trace[1730004718] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:930; }","duration":"146.758132ms","start":"2026-05-27T13:48:07.756674Z","end":"2026-05-27T13:48:07.903432Z","steps":["trace[1730004718] 'read index received' (duration: 82.902224ms)","trace[1730004718] 'applied index is now lower than readState.Index' (duration: 63.854448ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:48:07.90361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.921968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:48:07.903651Z","caller":"traceutil/trace.go:171","msg":"trace[1240318414] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:896; }","duration":"147.022611ms","start":"2026-05-27T13:48:07.756616Z","end":"2026-05-27T13:48:07.903639Z","steps":["trace[1240318414] 'agreement among raft nodes before linearized reading' (duration: 146.941589ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:09.627018Z","caller":"traceutil/trace.go:171","msg":"trace[644381267] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"160.115881ms","start":"2026-05-27T13:48:09.466875Z","end":"2026-05-27T13:48:09.626991Z","steps":["trace[644381267] 'process raft request' (duration: 109.191315ms)","trace[644381267] 'compare' (duration: 50.74201ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:48:09.62724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.861741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-27T13:48:09.627366Z","caller":"traceutil/trace.go:171","msg":"trace[1111099769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:908; }","duration":"160.049248ms","start":"2026-05-27T13:48:09.467295Z","end":"2026-05-27T13:48:09.627344Z","steps":["trace[1111099769] 'agreement among raft nodes before linearized reading' (duration: 159.697075ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:15.386367Z","caller":"traceutil/trace.go:171","msg":"trace[1321584992] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"255.70639ms","start":"2026-05-27T13:48:15.130642Z","end":"2026-05-27T13:48:15.386348Z","steps":["trace[1321584992] 'process raft request' (duration: 255.597616ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:15.684056Z","caller":"traceutil/trace.go:171","msg":"trace[1771684012] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1005; }","duration":"118.51302ms","start":"2026-05-27T13:48:15.565523Z","end":"2026-05-27T13:48:15.684036Z","steps":["trace[1771684012] 'read index received' (duration: 118.266621ms)","trace[1771684012] 'applied index is now lower than readState.Index' (duration: 245.459µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:48:15.684113Z","caller":"traceutil/trace.go:171","msg":"trace[1166774037] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"289.272484ms","start":"2026-05-27T13:48:15.394815Z","end":"2026-05-27T13:48:15.684087Z","steps":["trace[1166774037] 'process raft request' (duration: 289.113368ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:15.684125Z","caller":"traceutil/trace.go:171","msg":"trace[1766946487] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"291.130082ms","start":"2026-05-27T13:48:15.392961Z","end":"2026-05-27T13:48:15.684091Z","steps":["trace[1766946487] 'process raft request' (duration: 290.847442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:48:15.684482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.978636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T13:48:15.684547Z","caller":"traceutil/trace.go:171","msg":"trace[917316945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:969; }","duration":"108.08185ms","start":"2026-05-27T13:48:15.576449Z","end":"2026-05-27T13:48:15.684531Z","steps":["trace[917316945] 'agreement among raft nodes before linearized reading' (duration: 107.852332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:48:15.684234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.703218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:48:15.684854Z","caller":"traceutil/trace.go:171","msg":"trace[333438797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"119.358511ms","start":"2026-05-27T13:48:15.565481Z","end":"2026-05-27T13:48:15.684839Z","steps":["trace[333438797] 'agreement among raft nodes before linearized reading' (duration: 118.684597ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:15.799856Z","caller":"traceutil/trace.go:171","msg":"trace[698527712] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"108.067469ms","start":"2026-05-27T13:48:15.691748Z","end":"2026-05-27T13:48:15.799816Z","steps":["trace[698527712] 'process raft request' (duration: 78.694609ms)","trace[698527712] 'compare' (duration: 29.075319ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:48:15.800265Z","caller":"traceutil/trace.go:171","msg":"trace[1409475537] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"108.37889ms","start":"2026-05-27T13:48:15.691855Z","end":"2026-05-27T13:48:15.800234Z","steps":["trace[1409475537] 'process raft request' (duration: 107.907933ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:15.800331Z","caller":"traceutil/trace.go:171","msg":"trace[780344964] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"106.836464ms","start":"2026-05-27T13:48:15.693465Z","end":"2026-05-27T13:48:15.800302Z","steps":["trace[780344964] 'process raft request' (duration: 106.575685ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:15.800386Z","caller":"traceutil/trace.go:171","msg":"trace[1896574490] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"106.758651ms","start":"2026-05-27T13:48:15.69362Z","end":"2026-05-27T13:48:15.800379Z","steps":["trace[1896574490] 'process raft request' (duration: 106.589465ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:15.800568Z","caller":"traceutil/trace.go:171","msg":"trace[66314436] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"106.790432ms","start":"2026-05-27T13:48:15.693747Z","end":"2026-05-27T13:48:15.800538Z","steps":["trace[66314436] 'process raft request' (duration: 106.520012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:48:18.591076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.803204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:1 size:1464"} {"level":"info","ts":"2026-05-27T13:48:18.591161Z","caller":"traceutil/trace.go:171","msg":"trace[1439887083] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:1; response_revision:1017; }","duration":"250.928958ms","start":"2026-05-27T13:48:18.340213Z","end":"2026-05-27T13:48:18.591142Z","steps":["trace[1439887083] 'range keys from in-memory index tree' (duration: 250.669339ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:48:18.591404Z","caller":"traceutil/trace.go:171","msg":"trace[1465642664] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"192.499427ms","start":"2026-05-27T13:48:18.398887Z","end":"2026-05-27T13:48:18.591387Z","steps":["trace[1465642664] 'process raft request' (duration: 153.700809ms)","trace[1465642664] 'compare' (duration: 38.153064ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:48:48.879349Z","caller":"traceutil/trace.go:171","msg":"trace[372827820] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"165.714655ms","start":"2026-05-27T13:48:48.713612Z","end":"2026-05-27T13:48:48.879327Z","steps":["trace[372827820] 'process raft request' (duration: 129.237017ms)","trace[372827820] 'compare' (duration: 36.381234ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:49:06.385271Z","caller":"traceutil/trace.go:171","msg":"trace[160181368] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"100.268506ms","start":"2026-05-27T13:49:06.284976Z","end":"2026-05-27T13:49:06.385245Z","steps":["trace[160181368] 'process raft request' (duration: 100.12309ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:49:06.38689Z","caller":"traceutil/trace.go:171","msg":"trace[113406599] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"101.069038ms","start":"2026-05-27T13:49:06.285777Z","end":"2026-05-27T13:49:06.386846Z","steps":["trace[113406599] 'process raft request' (duration: 100.896611ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:49:08.565358Z","caller":"traceutil/trace.go:171","msg":"trace[1207254245] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"172.330027ms","start":"2026-05-27T13:49:08.392987Z","end":"2026-05-27T13:49:08.565317Z","steps":["trace[1207254245] 'process raft request' (duration: 169.584625ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:49:08.566506Z","caller":"traceutil/trace.go:171","msg":"trace[1599499666] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"172.531336ms","start":"2026-05-27T13:49:08.393948Z","end":"2026-05-27T13:49:08.566479Z","steps":["trace[1599499666] 'process raft request' (duration: 172.431412ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:49:20.806612Z","caller":"traceutil/trace.go:171","msg":"trace[1881014739] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"144.053725ms","start":"2026-05-27T13:49:20.662535Z","end":"2026-05-27T13:49:20.806589Z","steps":["trace[1881014739] 'read index received' (duration: 143.846166ms)","trace[1881014739] 'applied index is now lower than readState.Index' (duration: 206.569µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:49:20.806682Z","caller":"traceutil/trace.go:171","msg":"trace[1958790156] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"186.452447ms","start":"2026-05-27T13:49:20.620209Z","end":"2026-05-27T13:49:20.806662Z","steps":["trace[1958790156] 'process raft request' (duration: 186.226098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:49:20.806817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.255133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T13:49:20.80687Z","caller":"traceutil/trace.go:171","msg":"trace[144487447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1229; }","duration":"144.360747ms","start":"2026-05-27T13:49:20.662496Z","end":"2026-05-27T13:49:20.806857Z","steps":["trace[144487447] 'agreement among raft nodes before linearized reading' (duration: 144.18756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:49:31.141165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.744105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:49:31.141318Z","caller":"traceutil/trace.go:171","msg":"trace[773695079] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"229.662082ms","start":"2026-05-27T13:49:30.91163Z","end":"2026-05-27T13:49:31.141292Z","steps":["trace[773695079] 'process raft request' (duration: 76.653216ms)","trace[773695079] 'compare' (duration: 152.435953ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:49:34.68677Z","caller":"traceutil/trace.go:171","msg":"trace[709234810] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"100.395568ms","start":"2026-05-27T13:49:34.586344Z","end":"2026-05-27T13:49:34.686739Z","steps":["trace[709234810] 'process raft request' (duration: 72.129348ms)","trace[709234810] 'compare' (duration: 28.126664ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:49:35.380676Z","caller":"traceutil/trace.go:171","msg":"trace[1831470279] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"189.640023ms","start":"2026-05-27T13:49:35.191008Z","end":"2026-05-27T13:49:35.380648Z","steps":["trace[1831470279] 'process raft request' (duration: 189.474186ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:49:35.434159Z","caller":"traceutil/trace.go:171","msg":"trace[1876541003] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"116.107563ms","start":"2026-05-27T13:49:35.31803Z","end":"2026-05-27T13:49:35.434137Z","steps":["trace[1876541003] 'process raft request' (duration: 116.050311ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:49:35.434247Z","caller":"traceutil/trace.go:171","msg":"trace[925980792] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"124.237257ms","start":"2026-05-27T13:49:35.309973Z","end":"2026-05-27T13:49:35.43421Z","steps":["trace[925980792] 'process raft request' (duration: 121.705191ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:49:36.826358Z","caller":"traceutil/trace.go:171","msg":"trace[108771694] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"131.150282ms","start":"2026-05-27T13:49:36.695164Z","end":"2026-05-27T13:49:36.826314Z","steps":["trace[108771694] 'process raft request' (duration: 130.866911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:49:51.310666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.062582ms","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-27T13:49:51.310781Z","caller":"traceutil/trace.go:171","msg":"trace[1001385896] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1359; }","duration":"169.24862ms","start":"2026-05-27T13:49:51.141515Z","end":"2026-05-27T13:49:51.310764Z","steps":["trace[1001385896] 'range keys from in-memory index tree' (duration: 169.041801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:49:51.310961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.658529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:49:51.311091Z","caller":"traceutil/trace.go:171","msg":"trace[1632494848] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"383.59093ms","start":"2026-05-27T13:49:50.927478Z","end":"2026-05-27T13:49:51.311069Z","steps":["trace[1632494848] 'read index received' (duration: 56.632µs)","trace[1632494848] 'applied index is now lower than readState.Index' (duration: 383.532648ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:49:51.31113Z","caller":"traceutil/trace.go:171","msg":"trace[1580431820] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"618.16915ms","start":"2026-05-27T13:49:50.69292Z","end":"2026-05-27T13:49:51.311089Z","steps":["trace[1580431820] 'process raft request' (duration: 220.291931ms)","trace[1580431820] 'compare' (duration: 397.496523ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:49:51.311352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.873122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-27T13:49:51.311353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:49:50.692891Z","time spent":"618.359147ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:49:51.311415Z","caller":"traceutil/trace.go:171","msg":"trace[903017862] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1360; }","duration":"383.970886ms","start":"2026-05-27T13:49:50.92743Z","end":"2026-05-27T13:49:51.311401Z","steps":["trace[903017862] 'agreement among raft nodes before linearized reading' (duration: 383.756557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:49:51.31146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:49:50.927411Z","time spent":"384.038849ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T13:49:51.311457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.115944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-27T13:49:51.31151Z","caller":"traceutil/trace.go:171","msg":"trace[1227462320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1360; }","duration":"248.212658ms","start":"2026-05-27T13:49:51.063286Z","end":"2026-05-27T13:49:51.311499Z","steps":["trace[1227462320] 'agreement among raft nodes before linearized reading' (duration: 248.036821ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:49:58.185785Z","caller":"traceutil/trace.go:171","msg":"trace[1919810330] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"104.695185ms","start":"2026-05-27T13:49:58.081067Z","end":"2026-05-27T13:49:58.185762Z","steps":["trace[1919810330] 'process raft request' (duration: 104.56689ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:49:59.784645Z","caller":"traceutil/trace.go:171","msg":"trace[1846536943] linearizableReadLoop","detail":"{readStateIndex:1454; appliedIndex:1453; }","duration":"382.271075ms","start":"2026-05-27T13:49:59.402348Z","end":"2026-05-27T13:49:59.78462Z","steps":["trace[1846536943] 'read index received' (duration: 382.15593ms)","trace[1846536943] 'applied index is now lower than readState.Index' (duration: 113.785µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:49:59.784814Z","caller":"traceutil/trace.go:171","msg":"trace[1239458109] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"432.753668ms","start":"2026-05-27T13:49:59.352014Z","end":"2026-05-27T13:49:59.784768Z","steps":["trace[1239458109] 'process raft request' (duration: 432.439385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:49:59.784955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.570798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-27T13:49:59.785011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:49:59.35199Z","time spent":"432.917415ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:49:59.785003Z","caller":"traceutil/trace.go:171","msg":"trace[1414059225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1392; }","duration":"382.703874ms","start":"2026-05-27T13:49:59.402286Z","end":"2026-05-27T13:49:59.78499Z","steps":["trace[1414059225] 'agreement among raft nodes before linearized reading' (duration: 382.455263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:49:59.785109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:49:59.402265Z","time spent":"382.822769ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-27T13:49:59.805496Z","caller":"traceutil/trace.go:171","msg":"trace[894127842] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"250.57785ms","start":"2026-05-27T13:49:59.554886Z","end":"2026-05-27T13:49:59.805464Z","steps":["trace[894127842] 'process raft request' (duration: 250.376522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:49:59.805616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.533872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T13:49:59.805666Z","caller":"traceutil/trace.go:171","msg":"trace[1806326785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1393; }","duration":"255.616526ms","start":"2026-05-27T13:49:59.550036Z","end":"2026-05-27T13:49:59.805652Z","steps":["trace[1806326785] 'agreement among raft nodes before linearized reading' (duration: 255.495441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:49:59.805503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.522795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:49:59.805827Z","caller":"traceutil/trace.go:171","msg":"trace[1944757482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1393; }","duration":"242.88984ms","start":"2026-05-27T13:49:59.562926Z","end":"2026-05-27T13:49:59.805816Z","steps":["trace[1944757482] 'agreement among raft nodes before linearized reading' (duration: 242.501073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:49:59.80598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.164448ms","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-27T13:49:59.806118Z","caller":"traceutil/trace.go:171","msg":"trace[1585716571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1393; }","duration":"399.369797ms","start":"2026-05-27T13:49:59.406724Z","end":"2026-05-27T13:49:59.806094Z","steps":["trace[1585716571] 'agreement among raft nodes before linearized reading' (duration: 398.794342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:49:59.806223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:49:59.406709Z","time spent":"399.495043ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T13:50:00.203149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.710514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:50:00.203389Z","caller":"traceutil/trace.go:171","msg":"trace[305474482] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"393.078323ms","start":"2026-05-27T13:49:59.810291Z","end":"2026-05-27T13:50:00.20337Z","steps":["trace[305474482] 'process raft request' (duration: 92.103328ms)","trace[305474482] 'compare' (duration: 299.792025ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:50:00.203487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:49:59.810274Z","time spent":"393.186618ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:50:00.203646Z","caller":"traceutil/trace.go:171","msg":"trace[1194054183] linearizableReadLoop","detail":"{readStateIndex:1458; appliedIndex:1456; }","duration":"382.763121ms","start":"2026-05-27T13:49:59.820814Z","end":"2026-05-27T13:50:00.203577Z","steps":["trace[1194054183] 'read index received' (duration: 81.516174ms)","trace[1194054183] 'applied index is now lower than readState.Index' (duration: 301.244897ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:50:00.203618Z","caller":"traceutil/trace.go:171","msg":"trace[1017937381] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"392.802061ms","start":"2026-05-27T13:49:59.810789Z","end":"2026-05-27T13:50:00.203592Z","steps":["trace[1017937381] 'process raft request' (duration: 392.469827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:50:00.203786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.960659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:50:00.203851Z","caller":"traceutil/trace.go:171","msg":"trace[232324248] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1396; }","duration":"383.043913ms","start":"2026-05-27T13:49:59.820788Z","end":"2026-05-27T13:50:00.203832Z","steps":["trace[232324248] 'agreement among raft nodes before linearized reading' (duration: 382.948668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:50:00.203919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:49:59.82078Z","time spent":"383.124356ms","remote":"127.0.0.1:34216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-05-27T13:50:00.203919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:49:59.810776Z","time spent":"392.970468ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:50:06.438835Z","caller":"traceutil/trace.go:171","msg":"trace[458165953] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"206.816299ms","start":"2026-05-27T13:50:06.231997Z","end":"2026-05-27T13:50:06.438813Z","steps":["trace[458165953] 'process raft request' (duration: 154.508828ms)","trace[458165953] 'compare' (duration: 52.199566ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:50:07.024673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.496988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:6 size:2185"} {"level":"warn","ts":"2026-05-27T13:50:07.024703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.401729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium\" ","response":"range_response_count:1 size:1654"} {"level":"info","ts":"2026-05-27T13:50:07.024753Z","caller":"traceutil/trace.go:171","msg":"trace[548965468] range","detail":"{range_begin:/registry/clusterroles/cilium; range_end:; response_count:1; response_revision:1422; }","duration":"119.497013ms","start":"2026-05-27T13:50:06.905244Z","end":"2026-05-27T13:50:07.024741Z","steps":["trace[548965468] 'range keys from in-memory index tree' (duration: 119.263123ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:50:07.024753Z","caller":"traceutil/trace.go:171","msg":"trace[483604597] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:6; response_revision:1422; }","duration":"154.619153ms","start":"2026-05-27T13:50:06.870116Z","end":"2026-05-27T13:50:07.024736Z","steps":["trace[483604597] 'range keys from in-memory index tree' (duration: 154.378843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:50:07.024673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.109549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"info","ts":"2026-05-27T13:50:07.024821Z","caller":"traceutil/trace.go:171","msg":"trace[575212507] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:1422; }","duration":"140.291846ms","start":"2026-05-27T13:50:06.884518Z","end":"2026-05-27T13:50:07.02481Z","steps":["trace[575212507] 'range keys from in-memory index tree' (duration: 139.955853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:50:07.576239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.880674ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T13:50:07.576351Z","caller":"traceutil/trace.go:171","msg":"trace[1923746100] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1486; }","duration":"113.707064ms","start":"2026-05-27T13:50:07.462624Z","end":"2026-05-27T13:50:07.576331Z","steps":["trace[1923746100] 'read index received' (duration: 8.704575ms)","trace[1923746100] 'applied index is now lower than readState.Index' (duration: 105.000899ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:50:07.576605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.948025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:1 size:1944"} {"level":"info","ts":"2026-05-27T13:50:07.576697Z","caller":"traceutil/trace.go:171","msg":"trace[1728554213] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:1; response_revision:1422; }","duration":"114.081801ms","start":"2026-05-27T13:50:07.462593Z","end":"2026-05-27T13:50:07.576675Z","steps":["trace[1728554213] 'agreement among raft nodes before linearized reading' (duration: 113.83882ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:50:07.961102Z","caller":"traceutil/trace.go:171","msg":"trace[1529712370] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"136.61139ms","start":"2026-05-27T13:50:07.824467Z","end":"2026-05-27T13:50:07.961078Z","steps":["trace[1529712370] 'process raft request' (duration: 136.481934ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:50:08.375462Z","caller":"traceutil/trace.go:171","msg":"trace[754632560] linearizableReadLoop","detail":"{readStateIndex:1491; appliedIndex:1490; }","duration":"102.909051ms","start":"2026-05-27T13:50:08.272524Z","end":"2026-05-27T13:50:08.375433Z","steps":["trace[754632560] 'read index received' (duration: 102.671501ms)","trace[754632560] 'applied index is now lower than readState.Index' (duration: 236.47µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:50:08.375539Z","caller":"traceutil/trace.go:171","msg":"trace[645063799] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"132.894563ms","start":"2026-05-27T13:50:08.242621Z","end":"2026-05-27T13:50:08.375516Z","steps":["trace[645063799] 'process raft request' (duration: 132.576579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:50:08.37568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.13125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:1 size:706"} {"level":"info","ts":"2026-05-27T13:50:08.375748Z","caller":"traceutil/trace.go:171","msg":"trace[451632285] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:1; response_revision:1426; }","duration":"103.241386ms","start":"2026-05-27T13:50:08.272487Z","end":"2026-05-27T13:50:08.375728Z","steps":["trace[451632285] 'agreement among raft nodes before linearized reading' (duration: 103.095059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:50:08.375932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.756829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-05-27T13:50:08.376337Z","caller":"traceutil/trace.go:171","msg":"trace[1327115318] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:1426; }","duration":"101.190379ms","start":"2026-05-27T13:50:08.275122Z","end":"2026-05-27T13:50:08.376313Z","steps":["trace[1327115318] 'agreement among raft nodes before linearized reading' (duration: 100.756489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:50:09.133949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.969613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:952"} {"level":"warn","ts":"2026-05-27T13:50:09.134063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.559595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"range_response_count:1 size:1142"} {"level":"info","ts":"2026-05-27T13:50:09.134135Z","caller":"traceutil/trace.go:171","msg":"trace[1226891593] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1428; }","duration":"200.160471ms","start":"2026-05-27T13:50:08.933943Z","end":"2026-05-27T13:50:09.134103Z","steps":["trace[1226891593] 'range keys from in-memory index tree' (duration: 199.883189ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:50:09.134137Z","caller":"traceutil/trace.go:171","msg":"trace[215641145] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1428; }","duration":"202.67826ms","start":"2026-05-27T13:50:08.931441Z","end":"2026-05-27T13:50:09.134119Z","steps":["trace[215641145] 'range keys from in-memory index tree' (duration: 202.42729ms)"],"step_count":1}