{"level":"warn","ts":"2026-05-21T15:29:25.821176Z","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-21T15:29:25.821319Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.174: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.174:2380","--initial-cluster=instance=https://199.19.213.174:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.174:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.174: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-21T15:29:25.82139Z","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-21T15:29:25.821402Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.174:2380"]} {"level":"info","ts":"2026-05-21T15:29:25.821421Z","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-21T15:29:25.821982Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.174:2379"]} {"level":"info","ts":"2026-05-21T15:29:25.822051Z","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.174:2380"],"listen-peer-urls":["https://199.19.213.174:2380"],"advertise-client-urls":["https://199.19.213.174:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.174:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.174: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-21T15:29:25.828409Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.133486ms"} {"level":"info","ts":"2026-05-21T15:29:25.835815Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b3617fc4a93ad58b","cluster-id":"2f224b65169306de"} {"level":"info","ts":"2026-05-21T15:29:25.835938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T15:29:25.835966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b became follower at term 0"} {"level":"info","ts":"2026-05-21T15:29:25.836004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b3617fc4a93ad58b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T15:29:25.836016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b became follower at term 1"} {"level":"info","ts":"2026-05-21T15:29:25.836076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b switched to configuration voters=(12925752888159557003)"} {"level":"warn","ts":"2026-05-21T15:29:25.845597Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T15:29:25.84701Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T15:29:25.848558Z","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-21T15:29:25.851008Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b3617fc4a93ad58b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T15:29:25.851098Z","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-21T15:29:25.851156Z","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-21T15:29:25.851176Z","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-21T15:29:25.851176Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b3617fc4a93ad58b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T15:29:25.851983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b switched to configuration voters=(12925752888159557003)"} {"level":"info","ts":"2026-05-21T15:29:25.852063Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2f224b65169306de","local-member-id":"b3617fc4a93ad58b","added-peer-id":"b3617fc4a93ad58b","added-peer-peer-urls":["https://199.19.213.174:2380"]} {"level":"info","ts":"2026-05-21T15:29:25.855282Z","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-21T15:29:25.855344Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.174:2380"} {"level":"info","ts":"2026-05-21T15:29:25.855366Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.174:2380"} {"level":"info","ts":"2026-05-21T15:29:25.856204Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b3617fc4a93ad58b","initial-advertise-peer-urls":["https://199.19.213.174:2380"],"listen-peer-urls":["https://199.19.213.174:2380"],"advertise-client-urls":["https://199.19.213.174:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.174:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T15:29:25.856245Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T15:29:26.038092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T15:29:26.038137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T15:29:26.038154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b received MsgPreVoteResp from b3617fc4a93ad58b at term 1"} {"level":"info","ts":"2026-05-21T15:29:26.038167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b became candidate at term 2"} {"level":"info","ts":"2026-05-21T15:29:26.038174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b received MsgVoteResp from b3617fc4a93ad58b at term 2"} {"level":"info","ts":"2026-05-21T15:29:26.038189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3617fc4a93ad58b became leader at term 2"} {"level":"info","ts":"2026-05-21T15:29:26.0382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b3617fc4a93ad58b elected leader b3617fc4a93ad58b at term 2"} {"level":"info","ts":"2026-05-21T15:29:26.047903Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b3617fc4a93ad58b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.174:2379]}","request-path":"/0/members/b3617fc4a93ad58b/attributes","cluster-id":"2f224b65169306de","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T15:29:26.047944Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T15:29:26.048028Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T15:29:26.048269Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T15:29:26.049597Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T15:29:26.049643Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T15:29:26.051516Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.174:2379"} {"level":"info","ts":"2026-05-21T15:29:26.053701Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T15:29:26.055396Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2f224b65169306de","local-member-id":"b3617fc4a93ad58b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T15:29:26.055549Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T15:29:26.055628Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-21T15:29:28.721353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.333004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-21T15:29:28.721487Z","caller":"traceutil/trace.go:171","msg":"trace[1308082382] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:78; }","duration":"131.849405ms","start":"2026-05-21T15:29:28.589618Z","end":"2026-05-21T15:29:28.721468Z","steps":["trace[1308082382] 'read index received' (duration: 10.829922ms)","trace[1308082382] 'applied index is now lower than readState.Index' (duration: 121.018303ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:28.721599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.97149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T15:29:28.721576Z","caller":"traceutil/trace.go:171","msg":"trace[2073815100] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"132.114296ms","start":"2026-05-21T15:29:28.589404Z","end":"2026-05-21T15:29:28.721518Z","steps":["trace[2073815100] 'compare' (duration: 120.187268ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:28.721626Z","caller":"traceutil/trace.go:171","msg":"trace[780545031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:75; }","duration":"132.013452ms","start":"2026-05-21T15:29:28.589604Z","end":"2026-05-21T15:29:28.721617Z","steps":["trace[780545031] 'agreement among raft nodes before linearized reading' (duration: 131.919507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:29.863566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.654747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-21T15:29:29.863665Z","caller":"traceutil/trace.go:171","msg":"trace[322243569] linearizableReadLoop","detail":"{readStateIndex:95; appliedIndex:94; }","duration":"320.636653ms","start":"2026-05-21T15:29:29.543016Z","end":"2026-05-21T15:29:29.863652Z","steps":["trace[322243569] 'read index received' (duration: 26.991µs)","trace[322243569] 'applied index is now lower than readState.Index' (duration: 320.608712ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:29.863716Z","caller":"traceutil/trace.go:171","msg":"trace[835832514] transaction","detail":"{read_only:false; response_revision:91; number_of_response:1; }","duration":"831.022032ms","start":"2026-05-21T15:29:29.032655Z","end":"2026-05-21T15:29:29.863677Z","steps":["trace[835832514] 'process raft request' (duration: 80.208838ms)","trace[835832514] 'compare' (duration: 750.546993ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:29.86382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.79701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T15:29:29.863849Z","caller":"traceutil/trace.go:171","msg":"trace[1766851356] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:91; }","duration":"320.853972ms","start":"2026-05-21T15:29:29.542986Z","end":"2026-05-21T15:29:29.86384Z","steps":["trace[1766851356] 'agreement among raft nodes before linearized reading' (duration: 320.723827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:29.863904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:29.542971Z","time spent":"320.926606ms","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-05-21T15:29:29.86391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.585554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-21T15:29:29.863934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:29.032635Z","time spent":"831.181629ms","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1026,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T15:29:29.864008Z","caller":"traceutil/trace.go:171","msg":"trace[63551935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:91; }","duration":"274.72962ms","start":"2026-05-21T15:29:29.589246Z","end":"2026-05-21T15:29:29.863975Z","steps":["trace[63551935] 'agreement among raft nodes before linearized reading' (duration: 274.538092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:30.366953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387566597470375433,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T15:29:30.44201Z","caller":"traceutil/trace.go:171","msg":"trace[1619296718] linearizableReadLoop","detail":"{readStateIndex:96; appliedIndex:95; }","duration":"575.451308ms","start":"2026-05-21T15:29:29.86653Z","end":"2026-05-21T15:29:30.441982Z","steps":["trace[1619296718] 'read index received' (duration: 575.140175ms)","trace[1619296718] 'applied index is now lower than readState.Index' (duration: 309.853µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:30.442081Z","caller":"traceutil/trace.go:171","msg":"trace[1564315344] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"575.587014ms","start":"2026-05-21T15:29:29.866466Z","end":"2026-05-21T15:29:30.442053Z","steps":["trace[1564315344] 'process raft request' (duration: 575.315602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:30.442168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.598774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-dns\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T15:29:30.442212Z","caller":"traceutil/trace.go:171","msg":"trace[311416140] range","detail":"{range_begin:/registry/clusterroles/system:kube-dns; range_end:; response_count:0; response_revision:92; }","duration":"575.705759ms","start":"2026-05-21T15:29:29.86649Z","end":"2026-05-21T15:29:30.442195Z","steps":["trace[311416140] 'agreement among raft nodes before linearized reading' (duration: 575.591064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:30.44221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:29.866446Z","time spent":"575.702848ms","remote":"127.0.0.1:56264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T15:29:30.442266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:29.86647Z","time spent":"575.785112ms","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:kube-dns\" "} {"level":"warn","ts":"2026-05-21T15:29:31.100107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.913369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-21T15:29:31.100197Z","caller":"traceutil/trace.go:171","msg":"trace[53791283] linearizableReadLoop","detail":"{readStateIndex:123; appliedIndex:122; }","duration":"126.841501ms","start":"2026-05-21T15:29:30.973341Z","end":"2026-05-21T15:29:31.100183Z","steps":["trace[53791283] 'read index received' (duration: 13.733783ms)","trace[53791283] 'applied index is now lower than readState.Index' (duration: 113.107028ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:31.100258Z","caller":"traceutil/trace.go:171","msg":"trace[2027985119] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"128.151317ms","start":"2026-05-21T15:29:30.972056Z","end":"2026-05-21T15:29:31.100207Z","steps":["trace[2027985119] 'process raft request' (duration: 15.089941ms)","trace[2027985119] 'compare' (duration: 112.773024ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:31.100347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.976377ms","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-21T15:29:31.100417Z","caller":"traceutil/trace.go:171","msg":"trace[1165512389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:119; }","duration":"127.112233ms","start":"2026-05-21T15:29:30.973288Z","end":"2026-05-21T15:29:31.1004Z","steps":["trace[1165512389] 'agreement among raft nodes before linearized reading' (duration: 126.939036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:32.096327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387566597470375491,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T15:29:32.597024Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387566597470375491,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T15:29:32.653173Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.54925304s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T15:29:32.771891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.3025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-21T15:29:32.772261Z","caller":"traceutil/trace.go:171","msg":"trace[1981509718] linearizableReadLoop","detail":"{readStateIndex:125; appliedIndex:123; }","duration":"1.176123884s","start":"2026-05-21T15:29:31.596113Z","end":"2026-05-21T15:29:32.772237Z","steps":["trace[1981509718] 'read index received' (duration: 1.057356034s)","trace[1981509718] 'applied index is now lower than readState.Index' (duration: 118.76582ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:32.772309Z","caller":"traceutil/trace.go:171","msg":"trace[271899303] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"1.668402685s","start":"2026-05-21T15:29:31.103847Z","end":"2026-05-21T15:29:32.772249Z","steps":["trace[271899303] 'process raft request' (duration: 1.549669227s)","trace[271899303] 'compare' (duration: 117.31993ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:32.77249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:31.103832Z","time spent":"1.668578853s","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T15:29:32.772694Z","caller":"traceutil/trace.go:171","msg":"trace[209883065] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"1.667711216s","start":"2026-05-21T15:29:31.104817Z","end":"2026-05-21T15:29:32.772529Z","steps":["trace[209883065] 'process raft request' (duration: 1.667211775s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:32.772949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.207098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T15:29:32.773016Z","caller":"traceutil/trace.go:171","msg":"trace[1359671171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:121; }","duration":"995.305392ms","start":"2026-05-21T15:29:31.777694Z","end":"2026-05-21T15:29:32.772999Z","steps":["trace[1359671171] 'agreement among raft nodes before linearized reading' (duration: 995.140705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:32.773063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:31.777679Z","time spent":"995.374055ms","remote":"127.0.0.1:56364","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-21T15:29:32.773219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177095234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T15:29:32.773294Z","caller":"traceutil/trace.go:171","msg":"trace[996197692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:121; }","duration":"1.1772428s","start":"2026-05-21T15:29:31.596036Z","end":"2026-05-21T15:29:32.773279Z","steps":["trace[996197692] 'agreement among raft nodes before linearized reading' (duration: 1.176972979s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:32.772881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:31.104799Z","time spent":"1.667950796s","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":710,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T15:29:32.773336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:31.596019Z","time spent":"1.177307333s","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T15:29:33.066882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.086138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-21T15:29:33.067113Z","caller":"traceutil/trace.go:171","msg":"trace[1424077227] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"198.167315ms","start":"2026-05-21T15:29:32.868925Z","end":"2026-05-21T15:29:33.067093Z","steps":["trace[1424077227] 'process raft request' (duration: 198.065161ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:33.067565Z","caller":"traceutil/trace.go:171","msg":"trace[130688368] transaction","detail":"{read_only:false; response_revision:125; number_of_response:1; }","duration":"199.454798ms","start":"2026-05-21T15:29:32.868083Z","end":"2026-05-21T15:29:33.067537Z","steps":["trace[130688368] 'process raft request' (duration: 16.504505ms)","trace[130688368] 'compare' (duration: 181.952462ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:33.272012Z","caller":"traceutil/trace.go:171","msg":"trace[941584606] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"198.856329ms","start":"2026-05-21T15:29:33.073129Z","end":"2026-05-21T15:29:33.271985Z","steps":["trace[941584606] 'process raft request' (duration: 122.862241ms)","trace[941584606] 'compare' (duration: 75.843482ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:34.060212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.613307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:volume-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T15:29:34.060306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.050323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:34.060451Z","caller":"traceutil/trace.go:171","msg":"trace[303286705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:150; }","duration":"270.379877ms","start":"2026-05-21T15:29:33.790049Z","end":"2026-05-21T15:29:34.060428Z","steps":["trace[303286705] 'range keys from in-memory index tree' (duration: 269.995231ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:34.060465Z","caller":"traceutil/trace.go:171","msg":"trace[935111211] range","detail":"{range_begin:/registry/clusterrolebindings/system:volume-scheduler; range_end:; response_count:0; response_revision:150; }","duration":"270.746022ms","start":"2026-05-21T15:29:33.789536Z","end":"2026-05-21T15:29:34.060282Z","steps":["trace[935111211] 'range keys from in-memory index tree' (duration: 270.326445ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:34.271897Z","caller":"traceutil/trace.go:171","msg":"trace[1936747002] transaction","detail":"{read_only:false; response_revision:156; number_of_response:1; }","duration":"132.327997ms","start":"2026-05-21T15:29:34.13954Z","end":"2026-05-21T15:29:34.271868Z","steps":["trace[1936747002] 'process raft request' (duration: 67.824571ms)","trace[1936747002] 'compare' (duration: 64.376541ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:34.505902Z","caller":"traceutil/trace.go:171","msg":"trace[1550944241] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"159.119543ms","start":"2026-05-21T15:29:34.346751Z","end":"2026-05-21T15:29:34.505871Z","steps":["trace[1550944241] 'process raft request' (duration: 106.01732ms)","trace[1550944241] 'compare' (duration: 52.952327ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:34.737013Z","caller":"traceutil/trace.go:171","msg":"trace[2125747512] linearizableReadLoop","detail":"{readStateIndex:167; appliedIndex:166; }","duration":"148.209001ms","start":"2026-05-21T15:29:34.588772Z","end":"2026-05-21T15:29:34.736981Z","steps":["trace[2125747512] 'read index received' (duration: 92.149627ms)","trace[2125747512] 'applied index is now lower than readState.Index' (duration: 56.058104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:34.737153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.360938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:34.737161Z","caller":"traceutil/trace.go:171","msg":"trace[1270265884] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"174.628463ms","start":"2026-05-21T15:29:34.562492Z","end":"2026-05-21T15:29:34.73712Z","steps":["trace[1270265884] 'process raft request' (duration: 118.480765ms)","trace[1270265884] 'compare' (duration: 55.886237ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:34.737193Z","caller":"traceutil/trace.go:171","msg":"trace[1539576043] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:0; response_revision:163; }","duration":"148.444461ms","start":"2026-05-21T15:29:34.588731Z","end":"2026-05-21T15:29:34.737175Z","steps":["trace[1539576043] 'agreement among raft nodes before linearized reading' (duration: 148.365908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:34.98519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.301521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:34.985304Z","caller":"traceutil/trace.go:171","msg":"trace[190331887] linearizableReadLoop","detail":"{readStateIndex:174; appliedIndex:173; }","duration":"117.920752ms","start":"2026-05-21T15:29:34.867362Z","end":"2026-05-21T15:29:34.985282Z","steps":["trace[190331887] 'read index received' (duration: 26.101µs)","trace[190331887] 'applied index is now lower than readState.Index' (duration: 117.892911ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:34.985355Z","caller":"traceutil/trace.go:171","msg":"trace[1315993338] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"148.983052ms","start":"2026-05-21T15:29:34.83635Z","end":"2026-05-21T15:29:34.985333Z","steps":["trace[1315993338] 'process raft request' (duration: 25.458992ms)","trace[1315993338] 'compare' (duration: 123.146015ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:34.985501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.133912ms","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-21T15:29:34.985566Z","caller":"traceutil/trace.go:171","msg":"trace[1333714325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:170; }","duration":"118.216364ms","start":"2026-05-21T15:29:34.867315Z","end":"2026-05-21T15:29:34.985531Z","steps":["trace[1333714325] 'agreement among raft nodes before linearized reading' (duration: 118.050948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:34.992674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.662864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:34.992747Z","caller":"traceutil/trace.go:171","msg":"trace[788637122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:170; }","duration":"102.761338ms","start":"2026-05-21T15:29:34.889976Z","end":"2026-05-21T15:29:34.992737Z","steps":["trace[788637122] 'agreement among raft nodes before linearized reading' (duration: 102.668034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:35.264296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.029825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:35.264556Z","caller":"traceutil/trace.go:171","msg":"trace[2133337863] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"211.295512ms","start":"2026-05-21T15:29:35.053224Z","end":"2026-05-21T15:29:35.26452Z","steps":["trace[2133337863] 'process raft request' (duration: 63.970615ms)","trace[2133337863] 'compare' (duration: 146.88853ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:35.264621Z","caller":"traceutil/trace.go:171","msg":"trace[195736430] linearizableReadLoop","detail":"{readStateIndex:179; appliedIndex:177; }","duration":"193.170764ms","start":"2026-05-21T15:29:35.071432Z","end":"2026-05-21T15:29:35.264602Z","steps":["trace[195736430] 'read index received' (duration: 45.794805ms)","trace[195736430] 'applied index is now lower than readState.Index' (duration: 147.375019ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:35.264692Z","caller":"traceutil/trace.go:171","msg":"trace[691193702] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"206.662336ms","start":"2026-05-21T15:29:35.057989Z","end":"2026-05-21T15:29:35.264651Z","steps":["trace[691193702] 'process raft request' (duration: 206.398996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:35.264985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.53973ms","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-21T15:29:35.26503Z","caller":"traceutil/trace.go:171","msg":"trace[205706941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:174; }","duration":"193.622293ms","start":"2026-05-21T15:29:35.071397Z","end":"2026-05-21T15:29:35.265019Z","steps":["trace[205706941] 'agreement among raft nodes before linearized reading' (duration: 193.316861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:35.602441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.48131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:35.602683Z","caller":"traceutil/trace.go:171","msg":"trace[574901145] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"132.783113ms","start":"2026-05-21T15:29:35.469869Z","end":"2026-05-21T15:29:35.602652Z","steps":["trace[574901145] 'process raft request' (duration: 19.016622ms)","trace[574901145] 'compare' (duration: 113.335964ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:35.602968Z","caller":"traceutil/trace.go:171","msg":"trace[1471812734] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"132.18121ms","start":"2026-05-21T15:29:35.470761Z","end":"2026-05-21T15:29:35.602942Z","steps":["trace[1471812734] 'process raft request' (duration: 131.785674ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:35.803237Z","caller":"traceutil/trace.go:171","msg":"trace[720272036] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:211; }","duration":"119.119156ms","start":"2026-05-21T15:29:35.684099Z","end":"2026-05-21T15:29:35.803218Z","steps":["trace[720272036] 'read index received' (duration: 42.15186ms)","trace[720272036] 'applied index is now lower than readState.Index' (duration: 76.966636ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:35.803322Z","caller":"traceutil/trace.go:171","msg":"trace[1677075770] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"122.70545ms","start":"2026-05-21T15:29:35.68058Z","end":"2026-05-21T15:29:35.803286Z","steps":["trace[1677075770] 'process raft request' (duration: 45.630909ms)","trace[1677075770] 'compare' (duration: 76.881843ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:35.803617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.4694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18b19e490f2ab01f\" ","response":"range_response_count:1 size:657"} {"level":"info","ts":"2026-05-21T15:29:35.803696Z","caller":"traceutil/trace.go:171","msg":"trace[563954551] range","detail":"{range_begin:/registry/events/default/instance.18b19e490f2ab01f; range_end:; response_count:1; response_revision:207; }","duration":"119.606775ms","start":"2026-05-21T15:29:35.68407Z","end":"2026-05-21T15:29:35.803677Z","steps":["trace[563954551] 'agreement among raft nodes before linearized reading' (duration: 119.277952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:36.560059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.611969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:36.560181Z","caller":"traceutil/trace.go:171","msg":"trace[645981422] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"404.40383ms","start":"2026-05-21T15:29:36.155729Z","end":"2026-05-21T15:29:36.560133Z","steps":["trace[645981422] 'process raft request' (duration: 55.495369ms)","trace[645981422] 'compare' (duration: 348.508295ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:36.560525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:36.155701Z","time spent":"404.622149ms","remote":"127.0.0.1:56402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":646,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T15:29:36.785792Z","caller":"traceutil/trace.go:171","msg":"trace[2091965484] linearizableReadLoop","detail":"{readStateIndex:231; appliedIndex:230; }","duration":"222.502079ms","start":"2026-05-21T15:29:36.563262Z","end":"2026-05-21T15:29:36.785764Z","steps":["trace[2091965484] 'read index received' (duration: 133.870886ms)","trace[2091965484] 'applied index is now lower than readState.Index' (duration: 88.630163ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:36.785811Z","caller":"traceutil/trace.go:171","msg":"trace[450035340] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"605.7042ms","start":"2026-05-21T15:29:36.180077Z","end":"2026-05-21T15:29:36.785782Z","steps":["trace[450035340] 'process raft request' (duration: 517.002694ms)","trace[450035340] 'compare' (duration: 88.53684ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:36.786243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:36.180051Z","time spent":"605.842385ms","remote":"127.0.0.1:56146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":749,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T15:29:36.786256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.971218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:36.786331Z","caller":"traceutil/trace.go:171","msg":"trace[1010691584] range","detail":"{range_begin:/registry/roles/kube-public/system:controller:bootstrap-signer; range_end:; response_count:0; response_revision:226; }","duration":"223.059881ms","start":"2026-05-21T15:29:36.563232Z","end":"2026-05-21T15:29:36.786292Z","steps":["trace[1010691584] 'agreement among raft nodes before linearized reading' (duration: 222.640385ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:37.002667Z","caller":"traceutil/trace.go:171","msg":"trace[970588832] linearizableReadLoop","detail":"{readStateIndex:234; appliedIndex:233; }","duration":"134.489031ms","start":"2026-05-21T15:29:36.868152Z","end":"2026-05-21T15:29:37.002641Z","steps":["trace[970588832] 'read index received' (duration: 67.669402ms)","trace[970588832] 'applied index is now lower than readState.Index' (duration: 66.818539ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:37.0027Z","caller":"traceutil/trace.go:171","msg":"trace[132959151] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"175.210915ms","start":"2026-05-21T15:29:36.827461Z","end":"2026-05-21T15:29:37.002672Z","steps":["trace[132959151] 'process raft request' (duration: 108.419978ms)","trace[132959151] 'compare' (duration: 66.60811ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:37.002952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.143416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:37.003132Z","caller":"traceutil/trace.go:171","msg":"trace[1683112629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:229; }","duration":"113.401725ms","start":"2026-05-21T15:29:36.889712Z","end":"2026-05-21T15:29:37.003114Z","steps":["trace[1683112629] 'agreement among raft nodes before linearized reading' (duration: 113.138094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:37.002961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.791554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:37.003259Z","caller":"traceutil/trace.go:171","msg":"trace[1540628798] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader; range_end:; response_count:0; response_revision:229; }","duration":"135.072824ms","start":"2026-05-21T15:29:36.868104Z","end":"2026-05-21T15:29:37.003176Z","steps":["trace[1540628798] 'agreement among raft nodes before linearized reading' (duration: 134.632947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:37.240518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.601554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:37.240643Z","caller":"traceutil/trace.go:171","msg":"trace[263753621] linearizableReadLoop","detail":"{readStateIndex:237; appliedIndex:235; }","duration":"186.252429ms","start":"2026-05-21T15:29:37.054369Z","end":"2026-05-21T15:29:37.240621Z","steps":["trace[263753621] 'read index received' (duration: 28.161393ms)","trace[263753621] 'applied index is now lower than readState.Index' (duration: 158.089026ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:37.240805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.429316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T15:29:37.240855Z","caller":"traceutil/trace.go:171","msg":"trace[489113330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:232; }","duration":"186.510279ms","start":"2026-05-21T15:29:37.054335Z","end":"2026-05-21T15:29:37.240845Z","steps":["trace[489113330] 'agreement among raft nodes before linearized reading' (duration: 186.356993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:37.240829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.091587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:37.240973Z","caller":"traceutil/trace.go:171","msg":"trace[647509959] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:0; response_revision:232; }","duration":"156.219782ms","start":"2026-05-21T15:29:37.084698Z","end":"2026-05-21T15:29:37.240918Z","steps":["trace[647509959] 'agreement among raft nodes before linearized reading' (duration: 156.031865ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:37.240644Z","caller":"traceutil/trace.go:171","msg":"trace[260520694] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"220.231541ms","start":"2026-05-21T15:29:37.020389Z","end":"2026-05-21T15:29:37.240621Z","steps":["trace[260520694] 'process raft request' (duration: 95.981542ms)","trace[260520694] 'compare' (duration: 123.426386ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:37.503515Z","caller":"traceutil/trace.go:171","msg":"trace[1241261306] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:238; }","duration":"149.809451ms","start":"2026-05-21T15:29:37.353681Z","end":"2026-05-21T15:29:37.503491Z","steps":["trace[1241261306] 'read index received' (duration: 86.837103ms)","trace[1241261306] 'applied index is now lower than readState.Index' (duration: 62.971368ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:37.503907Z","caller":"traceutil/trace.go:171","msg":"trace[1188511247] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"258.009611ms","start":"2026-05-21T15:29:37.245875Z","end":"2026-05-21T15:29:37.503884Z","steps":["trace[1188511247] 'process raft request' (duration: 194.643338ms)","trace[1188511247] 'compare' (duration: 62.445427ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:37.503905Z","caller":"traceutil/trace.go:171","msg":"trace[1649705958] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"257.054884ms","start":"2026-05-21T15:29:37.246828Z","end":"2026-05-21T15:29:37.503883Z","steps":["trace[1649705958] 'process raft request' (duration: 256.586746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:37.504115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.362243ms","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-21T15:29:37.504451Z","caller":"traceutil/trace.go:171","msg":"trace[2146892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:235; }","duration":"150.753178ms","start":"2026-05-21T15:29:37.353668Z","end":"2026-05-21T15:29:37.504421Z","steps":["trace[2146892] 'agreement among raft nodes before linearized reading' (duration: 150.2732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:38.206989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.56739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:38.207159Z","caller":"traceutil/trace.go:171","msg":"trace[740481471] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"699.353269ms","start":"2026-05-21T15:29:37.507788Z","end":"2026-05-21T15:29:38.207141Z","steps":["trace[740481471] 'process raft request' (duration: 237.362173ms)","trace[740481471] 'compare' (duration: 461.455665ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:38.207215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:37.507778Z","time spent":"699.416851ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T15:29:38.20727Z","caller":"traceutil/trace.go:171","msg":"trace[1280853139] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"699.483723ms","start":"2026-05-21T15:29:37.507777Z","end":"2026-05-21T15:29:38.207261Z","steps":["trace[1280853139] 'process raft request' (duration: 699.369109ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:38.207342Z","caller":"traceutil/trace.go:171","msg":"trace[317201370] linearizableReadLoop","detail":"{readStateIndex:243; appliedIndex:240; }","duration":"699.480514ms","start":"2026-05-21T15:29:37.507811Z","end":"2026-05-21T15:29:38.207291Z","steps":["trace[317201370] 'read index received' (duration: 90.517297ms)","trace[317201370] 'applied index is now lower than readState.Index' (duration: 608.962527ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:38.207362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:37.507756Z","time spent":"699.540466ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:29:38.20749Z","caller":"traceutil/trace.go:171","msg":"trace[567136486] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"605.763561ms","start":"2026-05-21T15:29:37.60172Z","end":"2026-05-21T15:29:38.207483Z","steps":["trace[567136486] 'process raft request' (duration: 605.514611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:38.207734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.832147ms","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-21T15:29:38.207761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:37.601702Z","time spent":"605.962028ms","remote":"127.0.0.1:56146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T15:29:38.207856Z","caller":"traceutil/trace.go:171","msg":"trace[1086827695] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:239; }","duration":"700.054805ms","start":"2026-05-21T15:29:37.507774Z","end":"2026-05-21T15:29:38.207829Z","steps":["trace[1086827695] 'agreement among raft nodes before linearized reading' (duration: 699.604338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:38.207952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:37.507759Z","time spent":"700.179701ms","remote":"127.0.0.1:56174","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":"info","ts":"2026-05-21T15:29:38.2689Z","caller":"traceutil/trace.go:171","msg":"trace[1693047589] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"503.597319ms","start":"2026-05-21T15:29:37.765286Z","end":"2026-05-21T15:29:38.268883Z","steps":["trace[1693047589] 'process raft request' (duration: 503.498185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:38.269017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:37.765265Z","time spent":"503.693543ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3460,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:29:38.861566Z","caller":"traceutil/trace.go:171","msg":"trace[73565035] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"651.248184ms","start":"2026-05-21T15:29:38.210299Z","end":"2026-05-21T15:29:38.861547Z","steps":["trace[73565035] 'process raft request' (duration: 651.130689ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:38.861572Z","caller":"traceutil/trace.go:171","msg":"trace[1498462834] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"651.108948ms","start":"2026-05-21T15:29:38.210441Z","end":"2026-05-21T15:29:38.86155Z","steps":["trace[1498462834] 'process raft request' (duration: 651.017474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:38.861651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:38.210278Z","time spent":"651.334017ms","remote":"127.0.0.1:56146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T15:29:38.861659Z","caller":"traceutil/trace.go:171","msg":"trace[1045908005] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"650.791696ms","start":"2026-05-21T15:29:38.210836Z","end":"2026-05-21T15:29:38.861627Z","steps":["trace[1045908005] 'process raft request' (duration: 650.64584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:38.861696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:38.210433Z","time spent":"651.202362ms","remote":"127.0.0.1:56146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":676,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T15:29:38.861794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:38.210828Z","time spent":"650.90614ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":793,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T15:29:38.86238Z","caller":"traceutil/trace.go:171","msg":"trace[1399442667] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"934.812995ms","start":"2026-05-21T15:29:37.927523Z","end":"2026-05-21T15:29:38.862336Z","steps":["trace[1399442667] 'process raft request' (duration: 933.851928ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:38.861806Z","caller":"traceutil/trace.go:171","msg":"trace[240281221] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"956.59694ms","start":"2026-05-21T15:29:37.905202Z","end":"2026-05-21T15:29:38.861799Z","steps":["trace[240281221] 'process raft request' (duration: 942.909139ms)","trace[240281221] 'compare' (duration: 13.067947ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:38.862643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:37.905177Z","time spent":"957.421542ms","remote":"127.0.0.1:56264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T15:29:38.862549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:37.92749Z","time spent":"934.979732ms","remote":"127.0.0.1:56364","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-21T15:29:38.863119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.222231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:38.863173Z","caller":"traceutil/trace.go:171","msg":"trace[1941385961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:245; }","duration":"873.257042ms","start":"2026-05-21T15:29:37.989882Z","end":"2026-05-21T15:29:38.863139Z","steps":["trace[1941385961] 'agreement among raft nodes before linearized reading' (duration: 873.151768ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:38.863219Z","caller":"traceutil/trace.go:171","msg":"trace[2023099494] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:244; }","duration":"655.661355ms","start":"2026-05-21T15:29:38.20752Z","end":"2026-05-21T15:29:38.863181Z","steps":["trace[2023099494] 'read index received' (duration: 61.271265ms)","trace[2023099494] 'applied index is now lower than readState.Index' (duration: 594.13749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:38.863498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.209333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:38.863534Z","caller":"traceutil/trace.go:171","msg":"trace[919994659] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:245; }","duration":"994.278065ms","start":"2026-05-21T15:29:37.869245Z","end":"2026-05-21T15:29:38.863523Z","steps":["trace[919994659] 'agreement among raft nodes before linearized reading' (duration: 994.214633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:38.863567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:37.869227Z","time spent":"994.333818ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-05-21T15:29:38.863644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.19806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T15:29:38.863771Z","caller":"traceutil/trace.go:171","msg":"trace[1365084183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:245; }","duration":"653.293693ms","start":"2026-05-21T15:29:38.210389Z","end":"2026-05-21T15:29:38.863683Z","steps":["trace[1365084183] 'agreement among raft nodes before linearized reading' (duration: 653.154417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:38.86381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:38.210378Z","time spent":"653.417548ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T15:29:38.86381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207994931s","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-21T15:29:38.863879Z","caller":"traceutil/trace.go:171","msg":"trace[1151642559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:245; }","duration":"1.208095675s","start":"2026-05-21T15:29:37.655757Z","end":"2026-05-21T15:29:38.863852Z","steps":["trace[1151642559] 'agreement among raft nodes before linearized reading' (duration: 1.207884747s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:38.863915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:37.655736Z","time spent":"1.208170918s","remote":"127.0.0.1:56364","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-21T15:29:38.8647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:37.98984Z","time spent":"873.352337ms","remote":"127.0.0.1:56066","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-21T15:29:39.374291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.055559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:39.374506Z","caller":"traceutil/trace.go:171","msg":"trace[1328928153] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"508.070285ms","start":"2026-05-21T15:29:38.866379Z","end":"2026-05-21T15:29:39.374449Z","steps":["trace[1328928153] 'process raft request' (duration: 274.778997ms)","trace[1328928153] 'compare' (duration: 232.922284ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:39.374685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:38.866357Z","time spent":"508.242982ms","remote":"127.0.0.1:56146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T15:29:39.374519Z","caller":"traceutil/trace.go:171","msg":"trace[1360413046] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"505.820748ms","start":"2026-05-21T15:29:38.868681Z","end":"2026-05-21T15:29:39.374502Z","steps":["trace[1360413046] 'process raft request' (duration: 505.783097ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:39.374768Z","caller":"traceutil/trace.go:171","msg":"trace[389952] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"507.41938ms","start":"2026-05-21T15:29:38.867306Z","end":"2026-05-21T15:29:39.374726Z","steps":["trace[389952] 'process raft request' (duration: 507.099618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:39.374795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:38.868665Z","time spent":"506.083198ms","remote":"127.0.0.1:56364","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-21T15:29:39.374771Z","caller":"traceutil/trace.go:171","msg":"trace[455596263] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:250; }","duration":"508.081976ms","start":"2026-05-21T15:29:38.86661Z","end":"2026-05-21T15:29:39.374692Z","steps":["trace[455596263] 'read index received' (duration: 274.539408ms)","trace[455596263] 'applied index is now lower than readState.Index' (duration: 233.533407ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:39.374915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:38.867266Z","time spent":"507.572896ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:29:39.374947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.329255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3474"} {"level":"info","ts":"2026-05-21T15:29:39.374998Z","caller":"traceutil/trace.go:171","msg":"trace[1642580371] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:248; }","duration":"508.411159ms","start":"2026-05-21T15:29:38.866571Z","end":"2026-05-21T15:29:39.374982Z","steps":["trace[1642580371] 'agreement among raft nodes before linearized reading' (duration: 508.326805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:39.375031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:38.866554Z","time spent":"508.47068ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3497,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-21T15:29:39.375091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.810616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:39.375119Z","caller":"traceutil/trace.go:171","msg":"trace[1139619058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:248; }","duration":"175.868368ms","start":"2026-05-21T15:29:39.19924Z","end":"2026-05-21T15:29:39.375109Z","steps":["trace[1139619058] 'agreement among raft nodes before linearized reading' (duration: 175.821266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:39.375134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.004403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:39.375192Z","caller":"traceutil/trace.go:171","msg":"trace[1923730440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:248; }","duration":"508.109476ms","start":"2026-05-21T15:29:38.867064Z","end":"2026-05-21T15:29:39.375174Z","steps":["trace[1923730440] 'agreement among raft nodes before linearized reading' (duration: 507.94004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:39.375218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.843197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-21T15:29:39.375259Z","caller":"traceutil/trace.go:171","msg":"trace[346322379] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:248; }","duration":"506.89286ms","start":"2026-05-21T15:29:38.868353Z","end":"2026-05-21T15:29:39.375246Z","steps":["trace[346322379] 'agreement among raft nodes before linearized reading' (duration: 506.804676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:39.375243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:38.867048Z","time spent":"508.182839ms","remote":"127.0.0.1:56078","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-21T15:29:39.37529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:38.868346Z","time spent":"506.937521ms","remote":"127.0.0.1:56174","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-21T15:29:40.251411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.891441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:40.251517Z","caller":"traceutil/trace.go:171","msg":"trace[843601776] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"873.035153ms","start":"2026-05-21T15:29:39.378465Z","end":"2026-05-21T15:29:40.2515Z","steps":["trace[843601776] 'process raft request' (duration: 102.997466ms)","trace[843601776] 'compare' (duration: 769.748216ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:40.251611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:39.378448Z","time spent":"873.102376ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T15:29:40.267636Z","caller":"traceutil/trace.go:171","msg":"trace[1973130147] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"887.76343ms","start":"2026-05-21T15:29:39.379854Z","end":"2026-05-21T15:29:40.267618Z","steps":["trace[1973130147] 'process raft request' (duration: 887.639676ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:40.267626Z","caller":"traceutil/trace.go:171","msg":"trace[2119114779] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"888.441256ms","start":"2026-05-21T15:29:39.37916Z","end":"2026-05-21T15:29:40.267601Z","steps":["trace[2119114779] 'process raft request' (duration: 888.274209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:40.267723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:39.379828Z","time spent":"887.859474ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T15:29:40.267721Z","caller":"traceutil/trace.go:171","msg":"trace[512221625] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"888.550351ms","start":"2026-05-21T15:29:39.379127Z","end":"2026-05-21T15:29:40.267678Z","steps":["trace[512221625] 'process raft request' (duration: 888.167066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:40.267786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:39.379137Z","time spent":"888.565091ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:29:40.267885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:39.379103Z","time spent":"888.703827ms","remote":"127.0.0.1:56146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T15:29:40.267891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.276962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:40.267927Z","caller":"traceutil/trace.go:171","msg":"trace[1479683089] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:254; }","duration":"376.921458ms","start":"2026-05-21T15:29:39.890611Z","end":"2026-05-21T15:29:40.267533Z","steps":["trace[1479683089] 'read index received' (duration: 376.662878ms)","trace[1479683089] 'applied index is now lower than readState.Index' (duration: 257.87µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:40.267943Z","caller":"traceutil/trace.go:171","msg":"trace[2064317507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:252; }","duration":"377.387346ms","start":"2026-05-21T15:29:39.890542Z","end":"2026-05-21T15:29:40.267929Z","steps":["trace[2064317507] 'agreement among raft nodes before linearized reading' (duration: 377.279382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:40.267978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:39.890488Z","time spent":"377.48331ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T15:29:40.511261Z","caller":"traceutil/trace.go:171","msg":"trace[1059834498] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:260; }","duration":"200.021608ms","start":"2026-05-21T15:29:40.311213Z","end":"2026-05-21T15:29:40.511235Z","steps":["trace[1059834498] 'read index received' (duration: 134.35175ms)","trace[1059834498] 'applied index is now lower than readState.Index' (duration: 65.668708ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:40.51142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.184564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:40.511405Z","caller":"traceutil/trace.go:171","msg":"trace[88445235] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"219.023467ms","start":"2026-05-21T15:29:40.292301Z","end":"2026-05-21T15:29:40.511325Z","steps":["trace[88445235] 'process raft request' (duration: 153.207604ms)","trace[88445235] 'compare' (duration: 64.93336ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:40.511459Z","caller":"traceutil/trace.go:171","msg":"trace[962643535] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:token-cleaner; range_end:; response_count:0; response_revision:256; }","duration":"200.278798ms","start":"2026-05-21T15:29:40.311166Z","end":"2026-05-21T15:29:40.511445Z","steps":["trace[962643535] 'agreement among raft nodes before linearized reading' (duration: 200.171204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:40.811968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.959937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:40.812056Z","caller":"traceutil/trace.go:171","msg":"trace[1517024912] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"273.645196ms","start":"2026-05-21T15:29:40.538391Z","end":"2026-05-21T15:29:40.812037Z","steps":["trace[1517024912] 'process raft request' (duration: 93.531013ms)","trace[1517024912] 'compare' (duration: 179.819882ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:41.239071Z","caller":"traceutil/trace.go:171","msg":"trace[242665301] linearizableReadLoop","detail":"{readStateIndex:266; appliedIndex:265; }","duration":"345.684218ms","start":"2026-05-21T15:29:40.893348Z","end":"2026-05-21T15:29:41.239032Z","steps":["trace[242665301] 'read index received' (duration: 332.899178ms)","trace[242665301] 'applied index is now lower than readState.Index' (duration: 12.78349ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:41.23919Z","caller":"traceutil/trace.go:171","msg":"trace[1507109850] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"415.44191ms","start":"2026-05-21T15:29:40.823701Z","end":"2026-05-21T15:29:41.239143Z","steps":["trace[1507109850] 'process raft request' (duration: 402.573767ms)","trace[1507109850] 'compare' (duration: 12.590062ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:41.23934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.961428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"info","ts":"2026-05-21T15:29:41.239393Z","caller":"traceutil/trace.go:171","msg":"trace[2063400978] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:261; }","duration":"346.096733ms","start":"2026-05-21T15:29:40.893282Z","end":"2026-05-21T15:29:41.239379Z","steps":["trace[2063400978] 'agreement among raft nodes before linearized reading' (duration: 345.948917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:41.23945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:40.893254Z","time spent":"346.181816ms","remote":"127.0.0.1:56120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":136,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-05-21T15:29:41.239518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:40.823664Z","time spent":"415.629697ms","remote":"127.0.0.1:56264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:29:41.437211Z","caller":"traceutil/trace.go:171","msg":"trace[70201377] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:269; }","duration":"153.787781ms","start":"2026-05-21T15:29:41.283401Z","end":"2026-05-21T15:29:41.437189Z","steps":["trace[70201377] 'read index received' (duration: 62.635456ms)","trace[70201377] 'applied index is now lower than readState.Index' (duration: 91.151425ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:41.437271Z","caller":"traceutil/trace.go:171","msg":"trace[1422090936] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"160.567446ms","start":"2026-05-21T15:29:41.276667Z","end":"2026-05-21T15:29:41.437234Z","steps":["trace[1422090936] 'process raft request' (duration: 69.39859ms)","trace[1422090936] 'compare' (duration: 90.824743ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:41.437336Z","caller":"traceutil/trace.go:171","msg":"trace[1125905856] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"160.139239ms","start":"2026-05-21T15:29:41.277184Z","end":"2026-05-21T15:29:41.437323Z","steps":["trace[1125905856] 'process raft request' (duration: 159.943032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:41.437561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.147615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.174\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:41.437645Z","caller":"traceutil/trace.go:171","msg":"trace[1686047466] range","detail":"{range_begin:/registry/masterleases/199.19.213.174; range_end:; response_count:0; response_revision:266; }","duration":"154.278279ms","start":"2026-05-21T15:29:41.283348Z","end":"2026-05-21T15:29:41.437626Z","steps":["trace[1686047466] 'agreement among raft nodes before linearized reading' (duration: 154.141984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:41.89664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.597959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:41.897124Z","caller":"traceutil/trace.go:171","msg":"trace[290180527] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"455.64172ms","start":"2026-05-21T15:29:41.441439Z","end":"2026-05-21T15:29:41.897081Z","steps":["trace[290180527] 'process raft request' (duration: 291.342595ms)","trace[290180527] 'compare' (duration: 163.08794ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:41.897228Z","caller":"traceutil/trace.go:171","msg":"trace[1354735100] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"455.472794ms","start":"2026-05-21T15:29:41.441708Z","end":"2026-05-21T15:29:41.89718Z","steps":["trace[1354735100] 'process raft request' (duration: 455.241075ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:41.89728Z","caller":"traceutil/trace.go:171","msg":"trace[707581106] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"454.830829ms","start":"2026-05-21T15:29:41.442362Z","end":"2026-05-21T15:29:41.897192Z","steps":["trace[707581106] 'process raft request' (duration: 454.713215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:41.897435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:41.44235Z","time spent":"455.042688ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:29:41.897423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:41.441696Z","time spent":"455.603588ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T15:29:41.897841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:41.441422Z","time spent":"455.798616ms","remote":"127.0.0.1:56364","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-21T15:29:41.902695Z","caller":"traceutil/trace.go:171","msg":"trace[1924116109] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:272; }","duration":"263.428289ms","start":"2026-05-21T15:29:41.639246Z","end":"2026-05-21T15:29:41.902674Z","steps":["trace[1924116109] 'read index received' (duration: 93.473252ms)","trace[1924116109] 'applied index is now lower than readState.Index' (duration: 169.954137ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:41.902775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.514152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:41.902919Z","caller":"traceutil/trace.go:171","msg":"trace[109552362] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:271; }","duration":"263.704119ms","start":"2026-05-21T15:29:41.639205Z","end":"2026-05-21T15:29:41.90291Z","steps":["trace[109552362] 'agreement among raft nodes before linearized reading' (duration: 263.534143ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:41.903015Z","caller":"traceutil/trace.go:171","msg":"trace[1631545835] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"437.196019ms","start":"2026-05-21T15:29:41.465803Z","end":"2026-05-21T15:29:41.902999Z","steps":["trace[1631545835] 'process raft request' (duration: 436.739612ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:41.903166Z","caller":"traceutil/trace.go:171","msg":"trace[1599846595] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"432.252683ms","start":"2026-05-21T15:29:41.47089Z","end":"2026-05-21T15:29:41.903143Z","steps":["trace[1599846595] 'process raft request' (duration: 431.737574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:41.903236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:41.47086Z","time spent":"432.340016ms","remote":"127.0.0.1:56264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:29:41.903248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:41.46578Z","time spent":"437.300803ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:29:42.320528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.461672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:42.320661Z","caller":"traceutil/trace.go:171","msg":"trace[1709187524] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:277; }","duration":"415.485094ms","start":"2026-05-21T15:29:41.90516Z","end":"2026-05-21T15:29:42.320645Z","steps":["trace[1709187524] 'read index received' (duration: 131.797443ms)","trace[1709187524] 'applied index is now lower than readState.Index' (duration: 283.686691ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:42.32071Z","caller":"traceutil/trace.go:171","msg":"trace[381254962] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"418.385852ms","start":"2026-05-21T15:29:41.902301Z","end":"2026-05-21T15:29:42.320687Z","steps":["trace[381254962] 'process raft request' (duration: 134.715122ms)","trace[381254962] 'compare' (duration: 283.288446ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:42.320774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.617659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:42.320797Z","caller":"traceutil/trace.go:171","msg":"trace[1206983143] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:0; response_revision:272; }","duration":"415.671751ms","start":"2026-05-21T15:29:41.905118Z","end":"2026-05-21T15:29:42.32079Z","steps":["trace[1206983143] 'agreement among raft nodes before linearized reading' (duration: 415.605399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:42.320801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:41.902278Z","time spent":"418.479116ms","remote":"127.0.0.1:56168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T15:29:42.320825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:41.905105Z","time spent":"415.715402ms","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-21T15:29:42.321332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.746124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-21T15:29:42.321539Z","caller":"traceutil/trace.go:171","msg":"trace[1237055938] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:272; }","duration":"416.019663ms","start":"2026-05-21T15:29:41.905501Z","end":"2026-05-21T15:29:42.32152Z","steps":["trace[1237055938] 'agreement among raft nodes before linearized reading' (duration: 415.473363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:42.321581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:41.905486Z","time spent":"416.084896ms","remote":"127.0.0.1:56174","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-21T15:29:42.610344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.008488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:42.610585Z","caller":"traceutil/trace.go:171","msg":"trace[488243607] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"285.190585ms","start":"2026-05-21T15:29:42.325343Z","end":"2026-05-21T15:29:42.610534Z","steps":["trace[488243607] 'process raft request' (duration: 151.924778ms)","trace[488243607] 'compare' (duration: 132.698106ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:42.610674Z","caller":"traceutil/trace.go:171","msg":"trace[1026810558] linearizableReadLoop","detail":"{readStateIndex:283; appliedIndex:279; }","duration":"238.763855ms","start":"2026-05-21T15:29:42.371894Z","end":"2026-05-21T15:29:42.610657Z","steps":["trace[1026810558] 'read index received' (duration: 105.254438ms)","trace[1026810558] 'applied index is now lower than readState.Index' (duration: 133.508167ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:42.610763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.862959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-21T15:29:42.610789Z","caller":"traceutil/trace.go:171","msg":"trace[531679661] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:277; }","duration":"238.951872ms","start":"2026-05-21T15:29:42.371828Z","end":"2026-05-21T15:29:42.61078Z","steps":["trace[531679661] 'agreement among raft nodes before linearized reading' (duration: 238.87847ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:42.610979Z","caller":"traceutil/trace.go:171","msg":"trace[2128245975] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"284.672366ms","start":"2026-05-21T15:29:42.326297Z","end":"2026-05-21T15:29:42.61097Z","steps":["trace[2128245975] 'process raft request' (duration: 284.256221ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:42.611101Z","caller":"traceutil/trace.go:171","msg":"trace[1894159122] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"284.353824ms","start":"2026-05-21T15:29:42.326735Z","end":"2026-05-21T15:29:42.611089Z","steps":["trace[1894159122] 'process raft request' (duration: 283.868366ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:42.611036Z","caller":"traceutil/trace.go:171","msg":"trace[1663389706] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"284.924245ms","start":"2026-05-21T15:29:42.326064Z","end":"2026-05-21T15:29:42.610988Z","steps":["trace[1663389706] 'process raft request' (duration: 284.389385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:43.011258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.480699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:43.011402Z","caller":"traceutil/trace.go:171","msg":"trace[1659928794] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"365.051122ms","start":"2026-05-21T15:29:42.646325Z","end":"2026-05-21T15:29:43.011376Z","steps":["trace[1659928794] 'read index received' (duration: 196.326264ms)","trace[1659928794] 'applied index is now lower than readState.Index' (duration: 168.722668ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:43.011459Z","caller":"traceutil/trace.go:171","msg":"trace[1530770596] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"392.699456ms","start":"2026-05-21T15:29:42.618744Z","end":"2026-05-21T15:29:43.011444Z","steps":["trace[1530770596] 'process raft request' (duration: 223.931257ms)","trace[1530770596] 'compare' (duration: 168.25026ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:43.011527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:42.61873Z","time spent":"392.763428ms","remote":"127.0.0.1:56368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T15:29:43.011645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.301801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-21T15:29:43.011696Z","caller":"traceutil/trace.go:171","msg":"trace[1419640195] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:279; }","duration":"365.391664ms","start":"2026-05-21T15:29:42.646293Z","end":"2026-05-21T15:29:43.011685Z","steps":["trace[1419640195] 'agreement among raft nodes before linearized reading' (duration: 365.147055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:43.011738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:42.646276Z","time spent":"365.454387ms","remote":"127.0.0.1:56174","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":"info","ts":"2026-05-21T15:29:43.182647Z","caller":"traceutil/trace.go:171","msg":"trace[1571139166] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"167.784398ms","start":"2026-05-21T15:29:43.014822Z","end":"2026-05-21T15:29:43.182606Z","steps":["trace[1571139166] 'process raft request' (duration: 100.369728ms)","trace[1571139166] 'compare' (duration: 67.15771ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:43.423109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.813024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:43.423333Z","caller":"traceutil/trace.go:171","msg":"trace[938834935] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"232.424286ms","start":"2026-05-21T15:29:43.190872Z","end":"2026-05-21T15:29:43.423297Z","steps":["trace[938834935] 'process raft request' (duration: 119.308111ms)","trace[938834935] 'compare' (duration: 112.597736ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:43.423406Z","caller":"traceutil/trace.go:171","msg":"trace[73879054] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"229.873673ms","start":"2026-05-21T15:29:43.193468Z","end":"2026-05-21T15:29:43.423341Z","steps":["trace[73879054] 'process raft request' (duration: 229.740328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:43.745617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.307851ms","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-21T15:29:43.745693Z","caller":"traceutil/trace.go:171","msg":"trace[867865914] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:288; }","duration":"158.436705ms","start":"2026-05-21T15:29:43.587239Z","end":"2026-05-21T15:29:43.745675Z","steps":["trace[867865914] 'range keys from in-memory index tree' (duration: 158.178426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:43.745654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.06853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:43.745759Z","caller":"traceutil/trace.go:171","msg":"trace[1046655074] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:288; }","duration":"215.225966ms","start":"2026-05-21T15:29:43.530508Z","end":"2026-05-21T15:29:43.745734Z","steps":["trace[1046655074] 'range keys from in-memory index tree' (duration: 214.971907ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:44.029963Z","caller":"traceutil/trace.go:171","msg":"trace[469621281] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"194.762776ms","start":"2026-05-21T15:29:43.835135Z","end":"2026-05-21T15:29:44.029898Z","steps":["trace[469621281] 'process raft request' (duration: 99.706273ms)","trace[469621281] 'compare' (duration: 94.710571ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:44.029812Z","caller":"traceutil/trace.go:171","msg":"trace[259152547] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:296; }","duration":"193.980857ms","start":"2026-05-21T15:29:43.835802Z","end":"2026-05-21T15:29:44.029783Z","steps":["trace[259152547] 'read index received' (duration: 98.995487ms)","trace[259152547] 'applied index is now lower than readState.Index' (duration: 94.98436ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:44.030413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.574598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-21T15:29:44.030601Z","caller":"traceutil/trace.go:171","msg":"trace[753534162] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:291; }","duration":"194.792516ms","start":"2026-05-21T15:29:43.835773Z","end":"2026-05-21T15:29:44.030565Z","steps":["trace[753534162] 'agreement among raft nodes before linearized reading' (duration: 194.273318ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:44.056077Z","caller":"traceutil/trace.go:171","msg":"trace[237204846] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"216.550604ms","start":"2026-05-21T15:29:43.8395Z","end":"2026-05-21T15:29:44.056051Z","steps":["trace[237204846] 'process raft request' (duration: 216.294455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:44.056403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.938659ms","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-21T15:29:44.056534Z","caller":"traceutil/trace.go:171","msg":"trace[2101037448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:292; }","duration":"154.098156ms","start":"2026-05-21T15:29:43.902413Z","end":"2026-05-21T15:29:44.056511Z","steps":["trace[2101037448] 'agreement among raft nodes before linearized reading' (duration: 153.93042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:44.056486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.589444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T15:29:44.05694Z","caller":"traceutil/trace.go:171","msg":"trace[1651873633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:292; }","duration":"155.101942ms","start":"2026-05-21T15:29:43.901816Z","end":"2026-05-21T15:29:44.056918Z","steps":["trace[1651873633] 'agreement among raft nodes before linearized reading' (duration: 154.180999ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:44.279996Z","caller":"traceutil/trace.go:171","msg":"trace[2107680360] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"219.140021ms","start":"2026-05-21T15:29:44.060814Z","end":"2026-05-21T15:29:44.279954Z","steps":["trace[2107680360] 'process raft request' (duration: 218.869761ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:44.280168Z","caller":"traceutil/trace.go:171","msg":"trace[1873610951] linearizableReadLoop","detail":"{readStateIndex:301; appliedIndex:299; }","duration":"172.052635ms","start":"2026-05-21T15:29:44.108074Z","end":"2026-05-21T15:29:44.280127Z","steps":["trace[1873610951] 'read index received' (duration: 171.480244ms)","trace[1873610951] 'applied index is now lower than readState.Index' (duration: 570.181µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:44.280282Z","caller":"traceutil/trace.go:171","msg":"trace[1101317705] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"217.011354ms","start":"2026-05-21T15:29:44.063244Z","end":"2026-05-21T15:29:44.280255Z","steps":["trace[1101317705] 'process raft request' (duration: 216.63791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:44.280327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.230191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-21T15:29:44.280488Z","caller":"traceutil/trace.go:171","msg":"trace[782010828] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:295; }","duration":"172.433679ms","start":"2026-05-21T15:29:44.108041Z","end":"2026-05-21T15:29:44.280475Z","steps":["trace[782010828] 'agreement among raft nodes before linearized reading' (duration: 172.20034ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:44.454769Z","caller":"traceutil/trace.go:171","msg":"trace[984293251] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"108.598595ms","start":"2026-05-21T15:29:44.346151Z","end":"2026-05-21T15:29:44.45475Z","steps":["trace[984293251] 'process raft request' (duration: 108.555544ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:44.454838Z","caller":"traceutil/trace.go:171","msg":"trace[1254273239] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"133.968415ms","start":"2026-05-21T15:29:44.320829Z","end":"2026-05-21T15:29:44.454797Z","steps":["trace[1254273239] 'process raft request' (duration: 95.122427ms)","trace[1254273239] 'compare' (duration: 38.601039ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:44.837991Z","caller":"traceutil/trace.go:171","msg":"trace[1884839356] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"200.743855ms","start":"2026-05-21T15:29:44.637211Z","end":"2026-05-21T15:29:44.837955Z","steps":["trace[1884839356] 'process raft request' (duration: 193.825844ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:44.838048Z","caller":"traceutil/trace.go:171","msg":"trace[2009426288] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"200.733275ms","start":"2026-05-21T15:29:44.637272Z","end":"2026-05-21T15:29:44.838006Z","steps":["trace[2009426288] 'process raft request' (duration: 200.551568ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:44.838042Z","caller":"traceutil/trace.go:171","msg":"trace[1098317118] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"200.549078ms","start":"2026-05-21T15:29:44.637451Z","end":"2026-05-21T15:29:44.838001Z","steps":["trace[1098317118] 'process raft request' (duration: 200.446444ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.002728Z","caller":"traceutil/trace.go:171","msg":"trace[693798561] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"157.171026ms","start":"2026-05-21T15:29:44.845539Z","end":"2026-05-21T15:29:45.00271Z","steps":["trace[693798561] 'read index received' (duration: 94.590068ms)","trace[693798561] 'applied index is now lower than readState.Index' (duration: 62.580358ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:45.00283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.27588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-21T15:29:45.00285Z","caller":"traceutil/trace.go:171","msg":"trace[1172205311] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:308; }","duration":"157.353543ms","start":"2026-05-21T15:29:44.845489Z","end":"2026-05-21T15:29:45.002843Z","steps":["trace[1172205311] 'agreement among raft nodes before linearized reading' (duration: 157.27969ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.003058Z","caller":"traceutil/trace.go:171","msg":"trace[1663352138] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"160.021079ms","start":"2026-05-21T15:29:44.843Z","end":"2026-05-21T15:29:45.003021Z","steps":["trace[1663352138] 'process raft request' (duration: 97.166191ms)","trace[1663352138] 'compare' (duration: 62.451683ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:45.318865Z","caller":"traceutil/trace.go:171","msg":"trace[382553769] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"231.29122ms","start":"2026-05-21T15:29:45.087556Z","end":"2026-05-21T15:29:45.318847Z","steps":["trace[382553769] 'process raft request' (duration: 224.754506ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.524468Z","caller":"traceutil/trace.go:171","msg":"trace[1473582288] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"106.353252ms","start":"2026-05-21T15:29:45.418092Z","end":"2026-05-21T15:29:45.524445Z","steps":["trace[1473582288] 'process raft request' (duration: 58.931792ms)","trace[1473582288] 'compare' (duration: 47.303446ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:45.551864Z","caller":"traceutil/trace.go:171","msg":"trace[2051584065] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:320; }","duration":"106.57553ms","start":"2026-05-21T15:29:45.445261Z","end":"2026-05-21T15:29:45.551836Z","steps":["trace[2051584065] 'read index received' (duration: 31.829391ms)","trace[2051584065] 'applied index is now lower than readState.Index' (duration: 74.745499ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:45.551896Z","caller":"traceutil/trace.go:171","msg":"trace[2112513710] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"131.080548ms","start":"2026-05-21T15:29:45.420788Z","end":"2026-05-21T15:29:45.551869Z","steps":["trace[2112513710] 'process raft request' (duration: 130.913972ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.551963Z","caller":"traceutil/trace.go:171","msg":"trace[484230249] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"106.01264ms","start":"2026-05-21T15:29:45.445923Z","end":"2026-05-21T15:29:45.551935Z","steps":["trace[484230249] 'process raft request' (duration: 105.879265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:45.552025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.752057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-21T15:29:45.552071Z","caller":"traceutil/trace.go:171","msg":"trace[1007932332] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:317; }","duration":"106.85215ms","start":"2026-05-21T15:29:45.445208Z","end":"2026-05-21T15:29:45.55206Z","steps":["trace[1007932332] 'agreement among raft nodes before linearized reading' (duration: 106.722395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:45.622657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.153612ms","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-21T15:29:45.62276Z","caller":"traceutil/trace.go:171","msg":"trace[846405243] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:318; }","duration":"174.288547ms","start":"2026-05-21T15:29:45.448452Z","end":"2026-05-21T15:29:45.62274Z","steps":["trace[846405243] 'agreement among raft nodes before linearized reading' (duration: 174.10349ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.780536Z","caller":"traceutil/trace.go:171","msg":"trace[1004794787] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"228.035343ms","start":"2026-05-21T15:29:45.552449Z","end":"2026-05-21T15:29:45.780485Z","steps":["trace[1004794787] 'process raft request' (duration: 227.870597ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.780607Z","caller":"traceutil/trace.go:171","msg":"trace[550930239] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"212.061821ms","start":"2026-05-21T15:29:45.568522Z","end":"2026-05-21T15:29:45.780584Z","steps":["trace[550930239] 'process raft request' (duration: 211.954447ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.780669Z","caller":"traceutil/trace.go:171","msg":"trace[674607902] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:324; }","duration":"158.122477ms","start":"2026-05-21T15:29:45.62253Z","end":"2026-05-21T15:29:45.780653Z","steps":["trace[674607902] 'read index received' (duration: 157.788075ms)","trace[674607902] 'applied index is now lower than readState.Index' (duration: 333.322µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:45.780787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.655238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-21T15:29:45.780809Z","caller":"traceutil/trace.go:171","msg":"trace[243274384] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"225.286465ms","start":"2026-05-21T15:29:45.555513Z","end":"2026-05-21T15:29:45.780799Z","steps":["trace[243274384] 'process raft request' (duration: 224.887931ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.780818Z","caller":"traceutil/trace.go:171","msg":"trace[177419363] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:320; }","duration":"225.749002ms","start":"2026-05-21T15:29:45.55506Z","end":"2026-05-21T15:29:45.780809Z","steps":["trace[177419363] 'agreement among raft nodes before linearized reading' (duration: 225.661189ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.780983Z","caller":"traceutil/trace.go:171","msg":"trace[1906540879] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"211.966718ms","start":"2026-05-21T15:29:45.569006Z","end":"2026-05-21T15:29:45.780972Z","steps":["trace[1906540879] 'process raft request' (duration: 211.544713ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.780979Z","caller":"traceutil/trace.go:171","msg":"trace[1343374518] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"212.29937ms","start":"2026-05-21T15:29:45.568641Z","end":"2026-05-21T15:29:45.78094Z","steps":["trace[1343374518] 'process raft request' (duration: 211.863084ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.781057Z","caller":"traceutil/trace.go:171","msg":"trace[1672445158] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"212.079421ms","start":"2026-05-21T15:29:45.568966Z","end":"2026-05-21T15:29:45.781046Z","steps":["trace[1672445158] 'process raft request' (duration: 211.564813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:45.781106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.727615ms","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-21T15:29:45.781164Z","caller":"traceutil/trace.go:171","msg":"trace[245539339] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"212.127683ms","start":"2026-05-21T15:29:45.569029Z","end":"2026-05-21T15:29:45.781157Z","steps":["trace[245539339] 'process raft request' (duration: 211.592074ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.781204Z","caller":"traceutil/trace.go:171","msg":"trace[319688895] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:320; }","duration":"224.799967ms","start":"2026-05-21T15:29:45.556333Z","end":"2026-05-21T15:29:45.781133Z","steps":["trace[319688895] 'agreement among raft nodes before linearized reading' (duration: 224.730155ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.795617Z","caller":"traceutil/trace.go:171","msg":"trace[106360631] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"168.886223ms","start":"2026-05-21T15:29:45.626714Z","end":"2026-05-21T15:29:45.7956Z","steps":["trace[106360631] 'process raft request' (duration: 168.709186ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.795959Z","caller":"traceutil/trace.go:171","msg":"trace[814037083] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"169.142642ms","start":"2026-05-21T15:29:45.626794Z","end":"2026-05-21T15:29:45.795936Z","steps":["trace[814037083] 'process raft request' (duration: 168.722507ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.95246Z","caller":"traceutil/trace.go:171","msg":"trace[1955139133] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"136.503273ms","start":"2026-05-21T15:29:45.815933Z","end":"2026-05-21T15:29:45.952436Z","steps":["trace[1955139133] 'process raft request' (duration: 136.449321ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.952608Z","caller":"traceutil/trace.go:171","msg":"trace[476226789] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:334; }","duration":"146.199551ms","start":"2026-05-21T15:29:45.806377Z","end":"2026-05-21T15:29:45.952576Z","steps":["trace[476226789] 'read index received' (duration: 93.316365ms)","trace[476226789] 'applied index is now lower than readState.Index' (duration: 52.877255ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:45.952635Z","caller":"traceutil/trace.go:171","msg":"trace[134381875] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"146.857504ms","start":"2026-05-21T15:29:45.805668Z","end":"2026-05-21T15:29:45.952526Z","steps":["trace[134381875] 'process raft request' (duration: 94.03828ms)","trace[134381875] 'compare' (duration: 52.528753ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:45.952795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.822278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3998"} {"level":"warn","ts":"2026-05-21T15:29:45.952819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.043577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-21T15:29:45.952863Z","caller":"traceutil/trace.go:171","msg":"trace[955557644] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:325; }","duration":"143.146551ms","start":"2026-05-21T15:29:45.809706Z","end":"2026-05-21T15:29:45.952853Z","steps":["trace[955557644] 'agreement among raft nodes before linearized reading' (duration: 143.033057ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:45.95287Z","caller":"traceutil/trace.go:171","msg":"trace[1261320360] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:325; }","duration":"152.916341ms","start":"2026-05-21T15:29:45.799933Z","end":"2026-05-21T15:29:45.95285Z","steps":["trace[1261320360] 'agreement among raft nodes before linearized reading' (duration: 152.761696ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:46.090783Z","caller":"traceutil/trace.go:171","msg":"trace[844802271] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"133.745369ms","start":"2026-05-21T15:29:45.957007Z","end":"2026-05-21T15:29:46.090752Z","steps":["trace[844802271] 'process raft request' (duration: 108.545846ms)","trace[844802271] 'compare' (duration: 25.006607ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:46.091071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.127651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-05-21T15:29:46.091189Z","caller":"traceutil/trace.go:171","msg":"trace[2052636464] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:328; }","duration":"132.274396ms","start":"2026-05-21T15:29:45.958896Z","end":"2026-05-21T15:29:46.09117Z","steps":["trace[2052636464] 'agreement among raft nodes before linearized reading' (duration: 131.949935ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:46.090806Z","caller":"traceutil/trace.go:171","msg":"trace[2018046712] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:337; }","duration":"131.857012ms","start":"2026-05-21T15:29:45.958928Z","end":"2026-05-21T15:29:46.090785Z","steps":["trace[2018046712] 'read index received' (duration: 42.597627ms)","trace[2018046712] 'applied index is now lower than readState.Index' (duration: 89.258205ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:46.091227Z","caller":"traceutil/trace.go:171","msg":"trace[709951587] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"127.959452ms","start":"2026-05-21T15:29:45.963248Z","end":"2026-05-21T15:29:46.091207Z","steps":["trace[709951587] 'process raft request' (duration: 127.458884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:46.449479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.939554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:46.449606Z","caller":"traceutil/trace.go:171","msg":"trace[1444212728] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:345; }","duration":"265.087558ms","start":"2026-05-21T15:29:46.184495Z","end":"2026-05-21T15:29:46.449583Z","steps":["trace[1444212728] 'read index received' (duration: 129.915866ms)","trace[1444212728] 'applied index is now lower than readState.Index' (duration: 135.170552ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:46.449624Z","caller":"traceutil/trace.go:171","msg":"trace[1019578779] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"267.923679ms","start":"2026-05-21T15:29:46.181674Z","end":"2026-05-21T15:29:46.449598Z","steps":["trace[1019578779] 'process raft request' (duration: 132.784228ms)","trace[1019578779] 'compare' (duration: 134.758637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:46.44976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.258924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-21T15:29:46.4498Z","caller":"traceutil/trace.go:171","msg":"trace[1330546446] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:335; }","duration":"265.321777ms","start":"2026-05-21T15:29:46.184466Z","end":"2026-05-21T15:29:46.449788Z","steps":["trace[1330546446] 'agreement among raft nodes before linearized reading' (duration: 265.220483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:46.450007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.279819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T15:29:46.450099Z","caller":"traceutil/trace.go:171","msg":"trace[524550251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:335; }","duration":"165.415565ms","start":"2026-05-21T15:29:46.284665Z","end":"2026-05-21T15:29:46.45008Z","steps":["trace[524550251] 'agreement among raft nodes before linearized reading' (duration: 165.072733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:46.450359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.357549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:46.450461Z","caller":"traceutil/trace.go:171","msg":"trace[2105977207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:335; }","duration":"135.469813ms","start":"2026-05-21T15:29:46.314948Z","end":"2026-05-21T15:29:46.450418Z","steps":["trace[2105977207] 'agreement among raft nodes before linearized reading' (duration: 135.09613ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:46.651626Z","caller":"traceutil/trace.go:171","msg":"trace[1438378549] transaction","detail":"{read_only:false; number_of_response:0; response_revision:339; }","duration":"169.934914ms","start":"2026-05-21T15:29:46.481626Z","end":"2026-05-21T15:29:46.651561Z","steps":["trace[1438378549] 'process raft request' (duration: 169.858022ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:46.651727Z","caller":"traceutil/trace.go:171","msg":"trace[854453888] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"121.5452ms","start":"2026-05-21T15:29:46.530159Z","end":"2026-05-21T15:29:46.651704Z","steps":["trace[854453888] 'process raft request' (duration: 121.385294ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:46.651915Z","caller":"traceutil/trace.go:171","msg":"trace[614735872] transaction","detail":"{read_only:false; number_of_response:0; response_revision:339; }","duration":"170.213175ms","start":"2026-05-21T15:29:46.481612Z","end":"2026-05-21T15:29:46.651825Z","steps":["trace[614735872] 'process raft request' (duration: 111.076978ms)","trace[614735872] 'compare' (duration: 58.710952ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:46.652076Z","caller":"traceutil/trace.go:171","msg":"trace[2107216959] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:350; }","duration":"123.568041ms","start":"2026-05-21T15:29:46.528447Z","end":"2026-05-21T15:29:46.652015Z","steps":["trace[2107216959] 'read index received' (duration: 64.257198ms)","trace[2107216959] 'applied index is now lower than readState.Index' (duration: 59.299322ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:46.652189Z","caller":"traceutil/trace.go:171","msg":"trace[1097704635] transaction","detail":"{read_only:false; number_of_response:0; response_revision:339; }","duration":"170.221155ms","start":"2026-05-21T15:29:46.481935Z","end":"2026-05-21T15:29:46.652156Z","steps":["trace[1097704635] 'process raft request' (duration: 169.577832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:46.652494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.014627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7302"} {"level":"info","ts":"2026-05-21T15:29:46.652553Z","caller":"traceutil/trace.go:171","msg":"trace[1525844017] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:340; }","duration":"124.167382ms","start":"2026-05-21T15:29:46.528364Z","end":"2026-05-21T15:29:46.652531Z","steps":["trace[1525844017] 'agreement among raft nodes before linearized reading' (duration: 123.906283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:46.653419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.476298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-21T15:29:46.653539Z","caller":"traceutil/trace.go:171","msg":"trace[1852700119] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:340; }","duration":"123.596182ms","start":"2026-05-21T15:29:46.529869Z","end":"2026-05-21T15:29:46.653465Z","steps":["trace[1852700119] 'agreement among raft nodes before linearized reading' (duration: 122.457572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:46.975819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.144695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:46.975987Z","caller":"traceutil/trace.go:171","msg":"trace[689123846] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:358; }","duration":"132.464627ms","start":"2026-05-21T15:29:46.843505Z","end":"2026-05-21T15:29:46.97597Z","steps":["trace[689123846] 'read index received' (duration: 40.199096ms)","trace[689123846] 'applied index is now lower than readState.Index' (duration: 92.264521ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:46.97608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.57347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T15:29:46.976115Z","caller":"traceutil/trace.go:171","msg":"trace[646444971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:346; }","duration":"132.659323ms","start":"2026-05-21T15:29:46.843445Z","end":"2026-05-21T15:29:46.976104Z","steps":["trace[646444971] 'agreement among raft nodes before linearized reading' (duration: 132.57626ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:46.976116Z","caller":"traceutil/trace.go:171","msg":"trace[2124365558] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"244.411615ms","start":"2026-05-21T15:29:46.731663Z","end":"2026-05-21T15:29:46.976075Z","steps":["trace[2124365558] 'process raft request' (duration: 45.930258ms)","trace[2124365558] 'compare' (duration: 198.022681ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:46.976086Z","caller":"traceutil/trace.go:171","msg":"trace[1090155852] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"234.858877ms","start":"2026-05-21T15:29:46.741203Z","end":"2026-05-21T15:29:46.976062Z","steps":["trace[1090155852] 'process raft request' (duration: 234.717552ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:47.217892Z","caller":"traceutil/trace.go:171","msg":"trace[2051106230] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"204.73477ms","start":"2026-05-21T15:29:47.01309Z","end":"2026-05-21T15:29:47.217825Z","steps":["trace[2051106230] 'process raft request' (duration: 138.447456ms)","trace[2051106230] 'compare' (duration: 65.89384ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:47.218098Z","caller":"traceutil/trace.go:171","msg":"trace[1694326545] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"204.200112ms","start":"2026-05-21T15:29:47.013637Z","end":"2026-05-21T15:29:47.217837Z","steps":["trace[1694326545] 'process raft request' (duration: 203.959793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:47.554265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.874401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:47.554375Z","caller":"traceutil/trace.go:171","msg":"trace[837635222] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:364; }","duration":"253.110057ms","start":"2026-05-21T15:29:47.301218Z","end":"2026-05-21T15:29:47.554328Z","steps":["trace[837635222] 'read index received' (duration: 110.143603ms)","trace[837635222] 'applied index is now lower than readState.Index' (duration: 142.965383ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:47.554416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.20751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:47.554436Z","caller":"traceutil/trace.go:171","msg":"trace[737453008] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:0; response_revision:351; }","duration":"253.258512ms","start":"2026-05-21T15:29:47.301169Z","end":"2026-05-21T15:29:47.554428Z","steps":["trace[737453008] 'agreement among raft nodes before linearized reading' (duration: 253.223171ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:47.554458Z","caller":"traceutil/trace.go:171","msg":"trace[1574241370] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"325.268077ms","start":"2026-05-21T15:29:47.229153Z","end":"2026-05-21T15:29:47.554421Z","steps":["trace[1574241370] 'process raft request' (duration: 182.180259ms)","trace[1574241370] 'compare' (duration: 142.785877ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:47.554737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:47.229103Z","time spent":"325.471924ms","remote":"127.0.0.1:56264","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-21T15:29:47.888002Z","caller":"traceutil/trace.go:171","msg":"trace[1879093451] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"106.543996ms","start":"2026-05-21T15:29:47.781431Z","end":"2026-05-21T15:29:47.887975Z","steps":["trace[1879093451] 'process raft request' (duration: 74.266945ms)","trace[1879093451] 'compare' (duration: 32.090165ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:48.505982Z","caller":"traceutil/trace.go:171","msg":"trace[588412579] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"114.615156ms","start":"2026-05-21T15:29:48.391345Z","end":"2026-05-21T15:29:48.50596Z","steps":["trace[588412579] 'process raft request' (duration: 114.490592ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:48.515847Z","caller":"traceutil/trace.go:171","msg":"trace[1738218146] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:397; }","duration":"107.761329ms","start":"2026-05-21T15:29:48.408055Z","end":"2026-05-21T15:29:48.515817Z","steps":["trace[1738218146] 'read index received' (duration: 97.895027ms)","trace[1738218146] 'applied index is now lower than readState.Index' (duration: 9.864982ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:48.516336Z","caller":"traceutil/trace.go:171","msg":"trace[512221355] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"121.000288ms","start":"2026-05-21T15:29:48.395317Z","end":"2026-05-21T15:29:48.516318Z","steps":["trace[512221355] 'process raft request' (duration: 120.357946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:48.516609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.539476ms","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-21T15:29:48.516645Z","caller":"traceutil/trace.go:171","msg":"trace[1926050095] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:384; }","duration":"108.625408ms","start":"2026-05-21T15:29:48.408008Z","end":"2026-05-21T15:29:48.516634Z","steps":["trace[1926050095] 'agreement among raft nodes before linearized reading' (duration: 108.470943ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:48.69106Z","caller":"traceutil/trace.go:171","msg":"trace[2055050232] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"112.346837ms","start":"2026-05-21T15:29:48.578688Z","end":"2026-05-21T15:29:48.691035Z","steps":["trace[2055050232] 'read index received' (duration: 43.153047ms)","trace[2055050232] 'applied index is now lower than readState.Index' (duration: 69.19281ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:48.691209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.499072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:2205"} {"level":"info","ts":"2026-05-21T15:29:48.691153Z","caller":"traceutil/trace.go:171","msg":"trace[1920949125] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"112.909227ms","start":"2026-05-21T15:29:48.578199Z","end":"2026-05-21T15:29:48.691108Z","steps":["trace[1920949125] 'process raft request' (duration: 43.702226ms)","trace[1920949125] 'compare' (duration: 69.002564ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:48.69124Z","caller":"traceutil/trace.go:171","msg":"trace[1731142705] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:391; }","duration":"112.587946ms","start":"2026-05-21T15:29:48.578643Z","end":"2026-05-21T15:29:48.691231Z","steps":["trace[1731142705] 'agreement among raft nodes before linearized reading' (duration: 112.503043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:48.753439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.600412ms","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-21T15:29:48.753529Z","caller":"traceutil/trace.go:171","msg":"trace[601024091] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:393; }","duration":"145.728596ms","start":"2026-05-21T15:29:48.607785Z","end":"2026-05-21T15:29:48.753513Z","steps":["trace[601024091] 'agreement among raft nodes before linearized reading' (duration: 145.585081ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:48.753619Z","caller":"traceutil/trace.go:171","msg":"trace[523960424] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"174.034548ms","start":"2026-05-21T15:29:48.579546Z","end":"2026-05-21T15:29:48.75358Z","steps":["trace[523960424] 'process raft request' (duration: 173.684085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:48.753602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.815555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-05-21T15:29:48.753739Z","caller":"traceutil/trace.go:171","msg":"trace[825150081] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"172.933329ms","start":"2026-05-21T15:29:48.580786Z","end":"2026-05-21T15:29:48.753719Z","steps":["trace[825150081] 'process raft request' (duration: 172.544026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:48.753633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.846007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-05-21T15:29:48.753806Z","caller":"traceutil/trace.go:171","msg":"trace[816632811] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:393; }","duration":"158.122126ms","start":"2026-05-21T15:29:48.595662Z","end":"2026-05-21T15:29:48.753784Z","steps":["trace[816632811] 'agreement among raft nodes before linearized reading' (duration: 157.777034ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:48.75382Z","caller":"traceutil/trace.go:171","msg":"trace[1883052835] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:393; }","duration":"175.059884ms","start":"2026-05-21T15:29:48.578753Z","end":"2026-05-21T15:29:48.753813Z","steps":["trace[1883052835] 'agreement among raft nodes before linearized reading' (duration: 174.818175ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:49.129547Z","caller":"traceutil/trace.go:171","msg":"trace[607993444] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"115.876127ms","start":"2026-05-21T15:29:49.013648Z","end":"2026-05-21T15:29:49.129524Z","steps":["trace[607993444] 'process raft request' (duration: 66.334806ms)","trace[607993444] 'compare' (duration: 49.391195ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:50.566685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387566597470376098,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T15:29:51.067031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387566597470376098,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T15:29:51.568095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387566597470376098,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T15:29:51.901853Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.930278442s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T15:29:51.902236Z","caller":"traceutil/trace.go:171","msg":"trace[564083213] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"1.836440474s","start":"2026-05-21T15:29:50.065701Z","end":"2026-05-21T15:29:51.902142Z","steps":["trace[564083213] 'read index received' (duration: 1.836268678s)","trace[564083213] 'applied index is now lower than readState.Index' (duration: 170.966µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:51.902307Z","caller":"traceutil/trace.go:171","msg":"trace[1278019568] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"1.946870221s","start":"2026-05-21T15:29:49.955374Z","end":"2026-05-21T15:29:51.902244Z","steps":["trace[1278019568] 'process raft request' (duration: 1.946645303s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:51.902411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.836692503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-05-21T15:29:51.902443Z","caller":"traceutil/trace.go:171","msg":"trace[498562010] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:425; }","duration":"1.836744834s","start":"2026-05-21T15:29:50.065689Z","end":"2026-05-21T15:29:51.902433Z","steps":["trace[498562010] 'agreement among raft nodes before linearized reading' (duration: 1.83662096s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:51.902493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:50.065635Z","time spent":"1.836847907s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-21T15:29:51.902608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369060753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T15:29:51.902659Z","caller":"traceutil/trace.go:171","msg":"trace[2030404054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:425; }","duration":"1.369155556s","start":"2026-05-21T15:29:50.533489Z","end":"2026-05-21T15:29:51.902645Z","steps":["trace[2030404054] 'agreement among raft nodes before linearized reading' (duration: 1.369047323s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:51.902631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:49.955352Z","time spent":"1.94713751s","remote":"127.0.0.1:56146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T15:29:51.902691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:50.533473Z","time spent":"1.369211489s","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T15:29:51.902657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.691096319s","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-21T15:29:51.90281Z","caller":"traceutil/trace.go:171","msg":"trace[447448155] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:425; }","duration":"1.691303176s","start":"2026-05-21T15:29:50.211464Z","end":"2026-05-21T15:29:51.902767Z","steps":["trace[447448155] 'agreement among raft nodes before linearized reading' (duration: 1.690984305s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:51.902822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.929069ms","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-21T15:29:51.902878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:50.211444Z","time spent":"1.69141962s","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T15:29:51.902891Z","caller":"traceutil/trace.go:171","msg":"trace[2128933993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:425; }","duration":"767.984701ms","start":"2026-05-21T15:29:51.134882Z","end":"2026-05-21T15:29:51.902867Z","steps":["trace[2128933993] 'agreement among raft nodes before linearized reading' (duration: 767.855877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:51.902963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:51.134816Z","time spent":"768.130416ms","remote":"127.0.0.1:56364","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-21T15:29:51.902977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587446469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T15:29:51.902981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011875335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.174\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-21T15:29:51.903011Z","caller":"traceutil/trace.go:171","msg":"trace[1333115375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"1.587513121s","start":"2026-05-21T15:29:50.315488Z","end":"2026-05-21T15:29:51.903001Z","steps":["trace[1333115375] 'agreement among raft nodes before linearized reading' (duration: 1.58746432s)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:51.903022Z","caller":"traceutil/trace.go:171","msg":"trace[709109735] range","detail":"{range_begin:/registry/masterleases/199.19.213.174; range_end:; response_count:1; response_revision:425; }","duration":"1.011950828s","start":"2026-05-21T15:29:50.891058Z","end":"2026-05-21T15:29:51.903008Z","steps":["trace[709109735] 'agreement among raft nodes before linearized reading' (duration: 1.011881165s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:51.903036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:50.31547Z","time spent":"1.587560422s","remote":"127.0.0.1:56066","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-21T15:29:51.903049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:50.891038Z","time spent":"1.01200472s","remote":"127.0.0.1:56084","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.174\" "} {"level":"warn","ts":"2026-05-21T15:29:51.902678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074103288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T15:29:51.903149Z","caller":"traceutil/trace.go:171","msg":"trace[2029146082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:425; }","duration":"1.074639186s","start":"2026-05-21T15:29:50.828496Z","end":"2026-05-21T15:29:51.903135Z","steps":["trace[2029146082] 'agreement among raft nodes before linearized reading' (duration: 1.073968513s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:51.903195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:50.828477Z","time spent":"1.074704248s","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T15:29:52.687805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.302393ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-21T15:29:52.68925Z","caller":"traceutil/trace.go:171","msg":"trace[2095548097] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"453.119082ms","start":"2026-05-21T15:29:52.236089Z","end":"2026-05-21T15:29:52.689208Z","steps":["trace[2095548097] 'process raft request' (duration: 453.022599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:52.689424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:52.236065Z","time spent":"453.242425ms","remote":"127.0.0.1:56146","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-21T15:29:52.689547Z","caller":"traceutil/trace.go:171","msg":"trace[433622417] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"780.131305ms","start":"2026-05-21T15:29:51.909357Z","end":"2026-05-21T15:29:52.689488Z","steps":["trace[433622417] 'process raft request' (duration: 778.580844ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:52.689582Z","caller":"traceutil/trace.go:171","msg":"trace[956512263] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"778.861933ms","start":"2026-05-21T15:29:51.910692Z","end":"2026-05-21T15:29:52.689554Z","steps":["trace[956512263] 'process raft request' (duration: 777.633572ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:52.689591Z","caller":"traceutil/trace.go:171","msg":"trace[831117158] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"778.17356ms","start":"2026-05-21T15:29:51.911385Z","end":"2026-05-21T15:29:52.689559Z","steps":["trace[831117158] 'process raft request' (duration: 777.019221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:52.689715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:51.91067Z","time spent":"778.980537ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:29:52.689738Z","caller":"traceutil/trace.go:171","msg":"trace[1601688093] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"780.220208ms","start":"2026-05-21T15:29:51.909475Z","end":"2026-05-21T15:29:52.689695Z","steps":["trace[1601688093] 'process raft request' (duration: 778.76488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:52.689746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:51.961832Z","time spent":"727.909941ms","remote":"127.0.0.1:56570","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-21T15:29:52.689805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:51.904052Z","time spent":"785.748124ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T15:29:52.690174Z","caller":"traceutil/trace.go:171","msg":"trace[1496647558] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"785.91363ms","start":"2026-05-21T15:29:51.90422Z","end":"2026-05-21T15:29:52.690134Z","steps":["trace[1496647558] 'read index received' (duration: 248.280119ms)","trace[1496647558] 'applied index is now lower than readState.Index' (duration: 537.631341ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:52.690211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:51.911377Z","time spent":"778.292503ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:29:52.690273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:51.909464Z","time spent":"780.329122ms","remote":"127.0.0.1:56364","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-21T15:29:52.689812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:51.909326Z","time spent":"780.309791ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:29:52.691665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.44028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:52.691719Z","caller":"traceutil/trace.go:171","msg":"trace[1860582956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"787.559444ms","start":"2026-05-21T15:29:51.904145Z","end":"2026-05-21T15:29:52.691704Z","steps":["trace[1860582956] 'agreement among raft nodes before linearized reading' (duration: 786.090385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:52.69179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:51.904099Z","time spent":"787.682249ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T15:29:52.925939Z","caller":"traceutil/trace.go:171","msg":"trace[1662988049] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:446; }","duration":"228.62959ms","start":"2026-05-21T15:29:52.697288Z","end":"2026-05-21T15:29:52.925917Z","steps":["trace[1662988049] 'read index received' (duration: 217.49609ms)","trace[1662988049] 'applied index is now lower than readState.Index' (duration: 11.13246ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:52.925992Z","caller":"traceutil/trace.go:171","msg":"trace[1132800291] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"234.261548ms","start":"2026-05-21T15:29:52.691683Z","end":"2026-05-21T15:29:52.925945Z","steps":["trace[1132800291] 'process raft request' (duration: 223.094016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:52.926138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.806636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:52.92619Z","caller":"traceutil/trace.go:171","msg":"trace[681688460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:432; }","duration":"228.907379ms","start":"2026-05-21T15:29:52.69727Z","end":"2026-05-21T15:29:52.926177Z","steps":["trace[681688460] 'agreement among raft nodes before linearized reading' (duration: 228.737574ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:52.926188Z","caller":"traceutil/trace.go:171","msg":"trace[2096580994] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"233.298325ms","start":"2026-05-21T15:29:52.692861Z","end":"2026-05-21T15:29:52.926159Z","steps":["trace[2096580994] 'process raft request' (duration: 232.978724ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:54.505177Z","caller":"traceutil/trace.go:171","msg":"trace[69960479] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"186.853922ms","start":"2026-05-21T15:29:54.318303Z","end":"2026-05-21T15:29:54.505157Z","steps":["trace[69960479] 'process raft request' (duration: 186.593884ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:57.037417Z","caller":"traceutil/trace.go:171","msg":"trace[2142214476] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"205.658355ms","start":"2026-05-21T15:29:56.831727Z","end":"2026-05-21T15:29:57.037386Z","steps":["trace[2142214476] 'read index received' (duration: 204.253291ms)","trace[2142214476] 'applied index is now lower than readState.Index' (duration: 1.404154ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:57.037601Z","caller":"traceutil/trace.go:171","msg":"trace[2029202281] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"209.516588ms","start":"2026-05-21T15:29:56.828055Z","end":"2026-05-21T15:29:57.037571Z","steps":["trace[2029202281] 'process raft request' (duration: 207.969709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:57.037704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.947554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-21T15:29:57.037688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.903442ms","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-21T15:29:57.037741Z","caller":"traceutil/trace.go:171","msg":"trace[359110599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:444; }","duration":"206.054497ms","start":"2026-05-21T15:29:56.831674Z","end":"2026-05-21T15:29:57.037728Z","steps":["trace[359110599] 'agreement among raft nodes before linearized reading' (duration: 205.83996ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:57.037783Z","caller":"traceutil/trace.go:171","msg":"trace[1145297853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:444; }","duration":"206.055187ms","start":"2026-05-21T15:29:56.831704Z","end":"2026-05-21T15:29:57.037759Z","steps":["trace[1145297853] 'agreement among raft nodes before linearized reading' (duration: 205.871871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:57.037883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.585512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T15:29:57.037931Z","caller":"traceutil/trace.go:171","msg":"trace[2036169499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:444; }","duration":"205.649515ms","start":"2026-05-21T15:29:56.832264Z","end":"2026-05-21T15:29:57.037913Z","steps":["trace[2036169499] 'agreement among raft nodes before linearized reading' (duration: 205.561772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:57.427724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.071379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:57.427867Z","caller":"traceutil/trace.go:171","msg":"trace[1649551981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:447; }","duration":"114.272155ms","start":"2026-05-21T15:29:57.313572Z","end":"2026-05-21T15:29:57.427844Z","steps":["trace[1649551981] 'range keys from in-memory index tree' (duration: 113.958605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:59.481658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.313542ms","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-21T15:29:59.481806Z","caller":"traceutil/trace.go:171","msg":"trace[1741346423] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:449; }","duration":"135.476807ms","start":"2026-05-21T15:29:59.346306Z","end":"2026-05-21T15:29:59.481783Z","steps":["trace[1741346423] 'range keys from in-memory index tree' (duration: 135.289512ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:59.543349Z","caller":"traceutil/trace.go:171","msg":"trace[1461425836] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"452.362242ms","start":"2026-05-21T15:29:59.09096Z","end":"2026-05-21T15:29:59.543322Z","steps":["trace[1461425836] 'process raft request' (duration: 452.301551ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:59.543389Z","caller":"traceutil/trace.go:171","msg":"trace[649376603] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"452.447785ms","start":"2026-05-21T15:29:59.090914Z","end":"2026-05-21T15:29:59.543361Z","steps":["trace[649376603] 'process raft request' (duration: 452.179716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:59.543515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:59.090955Z","time spent":"452.478886ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:29:59.543522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:59.090905Z","time spent":"452.546807ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:29:59.859209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.340935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:59.85934Z","caller":"traceutil/trace.go:171","msg":"trace[1679674015] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:466; }","duration":"545.270234ms","start":"2026-05-21T15:29:59.314048Z","end":"2026-05-21T15:29:59.859318Z","steps":["trace[1679674015] 'read index received' (duration: 229.113581ms)","trace[1679674015] 'applied index is now lower than readState.Index' (duration: 316.155203ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:59.859425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.381568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:59.859459Z","caller":"traceutil/trace.go:171","msg":"trace[627177641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:452; }","duration":"545.45608ms","start":"2026-05-21T15:29:59.313993Z","end":"2026-05-21T15:29:59.859449Z","steps":["trace[627177641] 'agreement among raft nodes before linearized reading' (duration: 545.390588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:59.859504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:59.313974Z","time spent":"545.516482ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T15:29:59.859687Z","caller":"traceutil/trace.go:171","msg":"trace[1594268665] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"576.886002ms","start":"2026-05-21T15:29:59.282746Z","end":"2026-05-21T15:29:59.859632Z","steps":["trace[1594268665] 'process raft request' (duration: 472.00484ms)","trace[1594268665] 'compare' (duration: 104.198501ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:59.85988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:59.282726Z","time spent":"577.032576ms","remote":"127.0.0.1:56364","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-21T15:30:24.616984Z","caller":"traceutil/trace.go:171","msg":"trace[563465657] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"716.19464ms","start":"2026-05-21T15:30:23.900746Z","end":"2026-05-21T15:30:24.616941Z","steps":["trace[563465657] 'process raft request' (duration: 715.431961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:24.617161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:23.900704Z","time spent":"716.377694ms","remote":"127.0.0.1:56158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T15:30:25.982357Z","caller":"traceutil/trace.go:171","msg":"trace[1074391128] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"163.859405ms","start":"2026-05-21T15:30:25.818471Z","end":"2026-05-21T15:30:25.982331Z","steps":["trace[1074391128] 'process raft request' (duration: 68.99112ms)","trace[1074391128] 'compare' (duration: 94.549897ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:30:27.181947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.720642ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T15:30:27.182094Z","caller":"traceutil/trace.go:171","msg":"trace[1013079979] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"875.384829ms","start":"2026-05-21T15:30:26.30668Z","end":"2026-05-21T15:30:27.182065Z","steps":["trace[1013079979] 'read index received' (duration: 46.171µs)","trace[1013079979] 'applied index is now lower than readState.Index' (duration: 875.336698ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:30:27.182277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.569684ms","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-21T15:30:27.182342Z","caller":"traceutil/trace.go:171","msg":"trace[796526638] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:577; }","duration":"875.703017ms","start":"2026-05-21T15:30:26.306618Z","end":"2026-05-21T15:30:27.182321Z","steps":["trace[796526638] 'agreement among raft nodes before linearized reading' (duration: 875.579254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:27.182404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:26.306595Z","time spent":"875.794118ms","remote":"127.0.0.1:56158","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-21T15:30:27.196713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.477326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:30:27.196819Z","caller":"traceutil/trace.go:171","msg":"trace[1769694324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"882.63397ms","start":"2026-05-21T15:30:26.314152Z","end":"2026-05-21T15:30:27.196786Z","steps":["trace[1769694324] 'agreement among raft nodes before linearized reading' (duration: 882.473466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:27.196859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:26.31413Z","time spent":"882.721361ms","remote":"127.0.0.1:56066","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-21T15:30:27.196914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.967685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:30:27.197005Z","caller":"traceutil/trace.go:171","msg":"trace[1359886822] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:578; }","duration":"240.316614ms","start":"2026-05-21T15:30:26.956668Z","end":"2026-05-21T15:30:27.196985Z","steps":["trace[1359886822] 'agreement among raft nodes before linearized reading' (duration: 240.1256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:27.19708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.173095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T15:30:27.19711Z","caller":"traceutil/trace.go:171","msg":"trace[53247502] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:578; }","duration":"324.251817ms","start":"2026-05-21T15:30:26.872851Z","end":"2026-05-21T15:30:27.197103Z","steps":["trace[53247502] 'agreement among raft nodes before linearized reading' (duration: 324.192056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:27.197131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:26.872827Z","time spent":"324.298669ms","remote":"127.0.0.1:56168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-05-21T15:30:27.197223Z","caller":"traceutil/trace.go:171","msg":"trace[624418819] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"549.282514ms","start":"2026-05-21T15:30:26.647905Z","end":"2026-05-21T15:30:27.197188Z","steps":["trace[624418819] 'process raft request' (duration: 548.495416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:27.197485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:26.647875Z","time spent":"549.490929ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:30:34.31335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387566597470376631,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T15:30:34.813563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387566597470376631,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T15:30:34.935478Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.140611451s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T15:30:34.938698Z","caller":"traceutil/trace.go:171","msg":"trace[19752847] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"1.125691203s","start":"2026-05-21T15:30:33.812973Z","end":"2026-05-21T15:30:34.938664Z","steps":["trace[19752847] 'read index received' (duration: 1.122775978s)","trace[19752847] 'applied index is now lower than readState.Index' (duration: 2.913895ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:30:34.938944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.137678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:30:34.938988Z","caller":"traceutil/trace.go:171","msg":"trace[683454590] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:688; }","duration":"318.22659ms","start":"2026-05-21T15:30:34.620751Z","end":"2026-05-21T15:30:34.938978Z","steps":["trace[683454590] 'agreement among raft nodes before linearized reading' (duration: 318.117768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:34.938993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.52384ms","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-21T15:30:34.939207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:34.620654Z","time spent":"318.546477ms","remote":"127.0.0.1:56150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-05-21T15:30:34.939214Z","caller":"traceutil/trace.go:171","msg":"trace[766659550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:688; }","duration":"906.803896ms","start":"2026-05-21T15:30:34.032395Z","end":"2026-05-21T15:30:34.939199Z","steps":["trace[766659550] 'agreement among raft nodes before linearized reading' (duration: 906.443258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:34.939197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.578399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:30:34.939314Z","caller":"traceutil/trace.go:171","msg":"trace[1220825102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"343.957597ms","start":"2026-05-21T15:30:34.59534Z","end":"2026-05-21T15:30:34.939297Z","steps":["trace[1220825102] 'agreement among raft nodes before linearized reading' (duration: 343.571259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:34.939316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.229544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:30:34.939362Z","caller":"traceutil/trace.go:171","msg":"trace[1804685019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"623.305625ms","start":"2026-05-21T15:30:34.316042Z","end":"2026-05-21T15:30:34.939348Z","steps":["trace[1804685019] 'agreement among raft nodes before linearized reading' (duration: 623.242684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:34.939413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:34.316017Z","time spent":"623.386738ms","remote":"127.0.0.1:56078","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-21T15:30:34.939367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:34.595315Z","time spent":"344.04313ms","remote":"127.0.0.1:56066","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-21T15:30:34.939673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126656784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-21T15:30:34.939262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:34.032365Z","time spent":"906.886928ms","remote":"127.0.0.1:56364","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-21T15:30:34.939813Z","caller":"traceutil/trace.go:171","msg":"trace[540436424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:688; }","duration":"1.126862539s","start":"2026-05-21T15:30:33.812905Z","end":"2026-05-21T15:30:34.939768Z","steps":["trace[540436424] 'agreement among raft nodes before linearized reading' (duration: 1.125875887s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:34.939874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:33.812883Z","time spent":"1.126979452s","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T15:30:34.939363Z","caller":"traceutil/trace.go:171","msg":"trace[1095254174] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"1.145179193s","start":"2026-05-21T15:30:33.793581Z","end":"2026-05-21T15:30:34.93876Z","steps":["trace[1095254174] 'process raft request' (duration: 1.143223429s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:34.940278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:33.793556Z","time spent":"1.146438772s","remote":"127.0.0.1:56158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:30:35.802852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.924488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:30:35.80309Z","caller":"traceutil/trace.go:171","msg":"trace[1160754543] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:718; }","duration":"483.595974ms","start":"2026-05-21T15:30:35.319477Z","end":"2026-05-21T15:30:35.803073Z","steps":["trace[1160754543] 'read index received' (duration: 217.387009ms)","trace[1160754543] 'applied index is now lower than readState.Index' (duration: 266.207805ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:30:35.803214Z","caller":"traceutil/trace.go:171","msg":"trace[121719484] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"809.312315ms","start":"2026-05-21T15:30:34.993888Z","end":"2026-05-21T15:30:35.803201Z","steps":["trace[121719484] 'process raft request' (duration: 809.144451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:35.803281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:34.993855Z","time spent":"809.392507ms","remote":"127.0.0.1:56558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T15:30:35.803411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.933851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T15:30:35.803448Z","caller":"traceutil/trace.go:171","msg":"trace[1490270388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:691; }","duration":"483.972552ms","start":"2026-05-21T15:30:35.319462Z","end":"2026-05-21T15:30:35.803435Z","steps":["trace[1490270388] 'agreement among raft nodes before linearized reading' (duration: 483.847949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:35.803481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:35.319383Z","time spent":"484.090185ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T15:30:35.803082Z","caller":"traceutil/trace.go:171","msg":"trace[296877029] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"856.37234ms","start":"2026-05-21T15:30:34.946675Z","end":"2026-05-21T15:30:35.803047Z","steps":["trace[296877029] 'process raft request' (duration: 590.147225ms)","trace[296877029] 'compare' (duration: 265.770195ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:30:35.803756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:34.946645Z","time spent":"857.068926ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:30:35.803891Z","caller":"traceutil/trace.go:171","msg":"trace[850885909] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"855.228985ms","start":"2026-05-21T15:30:34.948651Z","end":"2026-05-21T15:30:35.80388Z","steps":["trace[850885909] 'process raft request' (duration: 854.321985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:35.803955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:34.948624Z","time spent":"855.299637ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:30:35.804019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.761663ms","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-21T15:30:35.804123Z","caller":"traceutil/trace.go:171","msg":"trace[1706053916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:691; }","duration":"468.937947ms","start":"2026-05-21T15:30:35.335156Z","end":"2026-05-21T15:30:35.804094Z","steps":["trace[1706053916] 'agreement among raft nodes before linearized reading' (duration: 468.641071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:35.804164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:35.335129Z","time spent":"469.026959ms","remote":"127.0.0.1:56364","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-21T15:30:37.539456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.571971901s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:30:37.539762Z","caller":"traceutil/trace.go:171","msg":"trace[1195289441] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"1.730607622s","start":"2026-05-21T15:30:35.809111Z","end":"2026-05-21T15:30:37.539718Z","steps":["trace[1195289441] 'process raft request' (duration: 158.263053ms)","trace[1195289441] 'compare' (duration: 1.571357348s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:30:37.539884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:35.809086Z","time spent":"1.730744635s","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:30:37.540225Z","caller":"traceutil/trace.go:171","msg":"trace[1263455991] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"1.730811946s","start":"2026-05-21T15:30:35.809398Z","end":"2026-05-21T15:30:37.540209Z","steps":["trace[1263455991] 'process raft request' (duration: 1.730167922s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:37.54032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:35.809354Z","time spent":"1.730909938s","remote":"127.0.0.1:56364","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-21T15:30:37.540538Z","caller":"traceutil/trace.go:171","msg":"trace[941027191] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:721; }","duration":"1.726580842s","start":"2026-05-21T15:30:35.813919Z","end":"2026-05-21T15:30:37.540499Z","steps":["trace[941027191] 'read index received' (duration: 153.575649ms)","trace[941027191] 'applied index is now lower than readState.Index' (duration: 1.573000853s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:30:37.602752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.788693533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T15:30:37.602816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287000172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:30:37.602883Z","caller":"traceutil/trace.go:171","msg":"trace[1405521986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"1.287119455s","start":"2026-05-21T15:30:36.315745Z","end":"2026-05-21T15:30:37.602865Z","steps":["trace[1405521986] 'agreement among raft nodes before linearized reading' (duration: 1.287003632s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:37.602931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:36.315715Z","time spent":"1.287203327s","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T15:30:37.602872Z","caller":"traceutil/trace.go:171","msg":"trace[1727905116] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:693; }","duration":"1.788961479s","start":"2026-05-21T15:30:35.813887Z","end":"2026-05-21T15:30:37.602848Z","steps":["trace[1727905116] 'agreement among raft nodes before linearized reading' (duration: 1.726778876s)","trace[1727905116] 'range keys from in-memory index tree' (duration: 61.907546ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:30:37.602952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.275276ms","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":"info","ts":"2026-05-21T15:30:37.603074Z","caller":"traceutil/trace.go:171","msg":"trace[757912035] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:694; }","duration":"839.511462ms","start":"2026-05-21T15:30:36.763536Z","end":"2026-05-21T15:30:37.603047Z","steps":["trace[757912035] 'agreement among raft nodes before linearized reading' (duration: 839.267407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:37.603046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:35.813873Z","time spent":"1.789159354s","remote":"127.0.0.1:56324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-21T15:30:37.603146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:36.76351Z","time spent":"839.618875ms","remote":"127.0.0.1:56464","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-21T15:30:38.173513Z","caller":"traceutil/trace.go:171","msg":"trace[1879916837] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"167.500561ms","start":"2026-05-21T15:30:38.005982Z","end":"2026-05-21T15:30:38.173483Z","steps":["trace[1879916837] 'process raft request' (duration: 166.896658ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:39.712954Z","caller":"traceutil/trace.go:171","msg":"trace[1328665081] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"167.599233ms","start":"2026-05-21T15:30:39.545326Z","end":"2026-05-21T15:30:39.712925Z","steps":["trace[1328665081] 'read index received' (duration: 167.417469ms)","trace[1328665081] 'applied index is now lower than readState.Index' (duration: 179.894µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:30:39.712994Z","caller":"traceutil/trace.go:171","msg":"trace[1479896110] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"182.243509ms","start":"2026-05-21T15:30:39.530714Z","end":"2026-05-21T15:30:39.712957Z","steps":["trace[1479896110] 'process raft request' (duration: 182.063495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:39.713244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.897419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T15:30:39.713301Z","caller":"traceutil/trace.go:171","msg":"trace[661383099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:731; }","duration":"167.980761ms","start":"2026-05-21T15:30:39.545306Z","end":"2026-05-21T15:30:39.713286Z","steps":["trace[661383099] 'agreement among raft nodes before linearized reading' (duration: 167.729316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:39.713353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.711496ms","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-21T15:30:39.713399Z","caller":"traceutil/trace.go:171","msg":"trace[729105520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:731; }","duration":"167.817888ms","start":"2026-05-21T15:30:39.54557Z","end":"2026-05-21T15:30:39.713387Z","steps":["trace[729105520] 'agreement among raft nodes before linearized reading' (duration: 167.599734ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:42.799684Z","caller":"traceutil/trace.go:171","msg":"trace[1371607412] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"267.661491ms","start":"2026-05-21T15:30:42.531989Z","end":"2026-05-21T15:30:42.799651Z","steps":["trace[1371607412] 'process raft request' (duration: 267.485047ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:48.057207Z","caller":"traceutil/trace.go:171","msg":"trace[207002511] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"192.869258ms","start":"2026-05-21T15:30:47.864276Z","end":"2026-05-21T15:30:48.057145Z","steps":["trace[207002511] 'process raft request' (duration: 192.685174ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:48.057438Z","caller":"traceutil/trace.go:171","msg":"trace[522316322] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:853; }","duration":"179.796373ms","start":"2026-05-21T15:30:47.877499Z","end":"2026-05-21T15:30:48.057295Z","steps":["trace[522316322] 'read index received' (duration: 179.769662ms)","trace[522316322] 'applied index is now lower than readState.Index' (duration: 23.981µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:30:48.057586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.058238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T15:30:48.057682Z","caller":"traceutil/trace.go:171","msg":"trace[1359021481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:817; }","duration":"180.20508ms","start":"2026-05-21T15:30:47.877461Z","end":"2026-05-21T15:30:48.057666Z","steps":["trace[1359021481] 'agreement among raft nodes before linearized reading' (duration: 179.916025ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:52.139537Z","caller":"traceutil/trace.go:171","msg":"trace[1308774390] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:876; }","duration":"316.67491ms","start":"2026-05-21T15:30:51.822835Z","end":"2026-05-21T15:30:52.13951Z","steps":["trace[1308774390] 'read index received' (duration: 316.369594ms)","trace[1308774390] 'applied index is now lower than readState.Index' (duration: 304.156µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:30:52.139688Z","caller":"traceutil/trace.go:171","msg":"trace[1619294431] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"329.621705ms","start":"2026-05-21T15:30:51.810056Z","end":"2026-05-21T15:30:52.139677Z","steps":["trace[1619294431] 'process raft request' (duration: 329.271328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:52.139816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:51.81003Z","time spent":"329.688107ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:30:52.140079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.23257ms","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-21T15:30:52.140144Z","caller":"traceutil/trace.go:171","msg":"trace[765997903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:840; }","duration":"317.326673ms","start":"2026-05-21T15:30:51.822799Z","end":"2026-05-21T15:30:52.140126Z","steps":["trace[765997903] 'agreement among raft nodes before linearized reading' (duration: 317.089188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:52.140189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:51.822775Z","time spent":"317.402834ms","remote":"127.0.0.1:56364","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-21T15:30:52.312051Z","caller":"traceutil/trace.go:171","msg":"trace[1735447455] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"166.364799ms","start":"2026-05-21T15:30:52.145638Z","end":"2026-05-21T15:30:52.312003Z","steps":["trace[1735447455] 'process raft request' (duration: 166.180096ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:52.312258Z","caller":"traceutil/trace.go:171","msg":"trace[312471871] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"166.577053ms","start":"2026-05-21T15:30:52.145638Z","end":"2026-05-21T15:30:52.312215Z","steps":["trace[312471871] 'process raft request' (duration: 166.328249ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:52.314718Z","caller":"traceutil/trace.go:171","msg":"trace[716234939] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"168.95567ms","start":"2026-05-21T15:30:52.145746Z","end":"2026-05-21T15:30:52.314701Z","steps":["trace[716234939] 'process raft request' (duration: 168.791207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:56.816052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387566597470377047,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T15:30:56.905844Z","caller":"traceutil/trace.go:171","msg":"trace[1719945648] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"746.948639ms","start":"2026-05-21T15:30:56.158822Z","end":"2026-05-21T15:30:56.905771Z","steps":["trace[1719945648] 'process raft request' (duration: 746.759076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:56.906199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:56.158794Z","time spent":"747.152503ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:30:56.980886Z","caller":"traceutil/trace.go:171","msg":"trace[895368620] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"487.185827ms","start":"2026-05-21T15:30:56.493678Z","end":"2026-05-21T15:30:56.980864Z","steps":["trace[895368620] 'process raft request' (duration: 487.001924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:56.980974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.468816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:30:56.980858Z","caller":"traceutil/trace.go:171","msg":"trace[1255782139] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:900; }","duration":"665.350784ms","start":"2026-05-21T15:30:56.315481Z","end":"2026-05-21T15:30:56.980832Z","steps":["trace[1255782139] 'read index received' (duration: 590.233921ms)","trace[1255782139] 'applied index is now lower than readState.Index' (duration: 75.115843ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:30:56.981008Z","caller":"traceutil/trace.go:171","msg":"trace[910063300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:865; }","duration":"665.581518ms","start":"2026-05-21T15:30:56.315418Z","end":"2026-05-21T15:30:56.980999Z","steps":["trace[910063300] 'agreement among raft nodes before linearized reading' (duration: 665.500176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:56.980999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:56.493642Z","time spent":"487.29689ms","remote":"127.0.0.1:56324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:30:56.98106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:56.315389Z","time spent":"665.66301ms","remote":"127.0.0.1:56066","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-21T15:30:56.981204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.343455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T15:30:56.981239Z","caller":"traceutil/trace.go:171","msg":"trace[225680401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:865; }","duration":"653.413256ms","start":"2026-05-21T15:30:56.327818Z","end":"2026-05-21T15:30:56.981232Z","steps":["trace[225680401] 'agreement among raft nodes before linearized reading' (duration: 653.302944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:56.981251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.306198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-21T15:30:56.981259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:56.327797Z","time spent":"653.456808ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T15:30:56.981277Z","caller":"traceutil/trace.go:171","msg":"trace[2115734111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:865; }","duration":"649.39602ms","start":"2026-05-21T15:30:56.331874Z","end":"2026-05-21T15:30:56.98127Z","steps":["trace[2115734111] 'agreement among raft nodes before linearized reading' (duration: 649.276768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:56.981319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.623606ms","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-21T15:30:56.981356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:56.33185Z","time spent":"649.495811ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T15:30:56.981405Z","caller":"traceutil/trace.go:171","msg":"trace[602242507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:865; }","duration":"647.750508ms","start":"2026-05-21T15:30:56.333637Z","end":"2026-05-21T15:30:56.981387Z","steps":["trace[602242507] 'agreement among raft nodes before linearized reading' (duration: 647.507084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:56.981442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:56.333626Z","time spent":"647.807849ms","remote":"127.0.0.1:56364","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-21T15:30:56.982035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.623793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T15:30:56.982089Z","caller":"traceutil/trace.go:171","msg":"trace[220786463] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:865; }","duration":"308.722505ms","start":"2026-05-21T15:30:56.673351Z","end":"2026-05-21T15:30:56.982073Z","steps":["trace[220786463] 'agreement among raft nodes before linearized reading' (duration: 307.809008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:56.982126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:56.673324Z","time spent":"308.791187ms","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-05-21T15:30:58.120514Z","caller":"traceutil/trace.go:171","msg":"trace[550455564] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"633.658433ms","start":"2026-05-21T15:30:57.486834Z","end":"2026-05-21T15:30:58.120492Z","steps":["trace[550455564] 'process raft request' (duration: 633.507781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:58.120671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:57.486807Z","time spent":"633.788496ms","remote":"127.0.0.1:56364","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-21T15:30:58.160778Z","caller":"traceutil/trace.go:171","msg":"trace[947866714] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:906; }","duration":"113.636588ms","start":"2026-05-21T15:30:58.047119Z","end":"2026-05-21T15:30:58.160756Z","steps":["trace[947866714] 'read index received' (duration: 73.239628ms)","trace[947866714] 'applied index is now lower than readState.Index' (duration: 40.39583ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:30:58.160837Z","caller":"traceutil/trace.go:171","msg":"trace[761120138] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"673.36144ms","start":"2026-05-21T15:30:57.487447Z","end":"2026-05-21T15:30:58.160809Z","steps":["trace[761120138] 'process raft request' (duration: 673.135416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:58.16091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.7715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:30:58.160938Z","caller":"traceutil/trace.go:171","msg":"trace[269412151] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:870; }","duration":"113.868742ms","start":"2026-05-21T15:30:58.047061Z","end":"2026-05-21T15:30:58.16093Z","steps":["trace[269412151] 'agreement among raft nodes before linearized reading' (duration: 113.78591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:58.160956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:57.487429Z","time spent":"673.460692ms","remote":"127.0.0.1:56264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:31:01.015736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.175437ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-21T15:31:02.013292Z","caller":"traceutil/trace.go:171","msg":"trace[56962875] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"108.038792ms","start":"2026-05-21T15:31:01.905224Z","end":"2026-05-21T15:31:02.013263Z","steps":["trace[56962875] 'process raft request' (duration: 107.881459ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:02.01982Z","caller":"traceutil/trace.go:171","msg":"trace[1260553000] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"112.782518ms","start":"2026-05-21T15:31:01.907017Z","end":"2026-05-21T15:31:02.0198Z","steps":["trace[1260553000] 'process raft request' (duration: 112.700756ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:03.953088Z","caller":"traceutil/trace.go:171","msg":"trace[205923372] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"154.553095ms","start":"2026-05-21T15:31:03.798507Z","end":"2026-05-21T15:31:03.95306Z","steps":["trace[205923372] 'process raft request' (duration: 154.365771ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:04.165107Z","caller":"traceutil/trace.go:171","msg":"trace[1661020978] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"207.166855ms","start":"2026-05-21T15:31:03.957919Z","end":"2026-05-21T15:31:04.165085Z","steps":["trace[1661020978] 'process raft request' (duration: 206.993522ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:04.283813Z","caller":"traceutil/trace.go:171","msg":"trace[2086537931] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"114.275758ms","start":"2026-05-21T15:31:04.169515Z","end":"2026-05-21T15:31:04.28379Z","steps":["trace[2086537931] 'process raft request' (duration: 114.227957ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:04.284046Z","caller":"traceutil/trace.go:171","msg":"trace[1575360134] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"325.985805ms","start":"2026-05-21T15:31:03.958037Z","end":"2026-05-21T15:31:04.284023Z","steps":["trace[1575360134] 'process raft request' (duration: 299.346343ms)","trace[1575360134] 'compare' (duration: 26.126373ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:31:04.284191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:31:03.958011Z","time spent":"326.118037ms","remote":"127.0.0.1:56264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3856,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:31:04.284264Z","caller":"traceutil/trace.go:171","msg":"trace[1375134142] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:973; }","duration":"124.759398ms","start":"2026-05-21T15:31:04.159494Z","end":"2026-05-21T15:31:04.284253Z","steps":["trace[1375134142] 'read index received' (duration: 5.417609ms)","trace[1375134142] 'applied index is now lower than readState.Index' (duration: 119.340459ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:31:04.284194Z","caller":"traceutil/trace.go:171","msg":"trace[1713861043] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"312.239654ms","start":"2026-05-21T15:31:03.971942Z","end":"2026-05-21T15:31:04.284181Z","steps":["trace[1713861043] 'process raft request' (duration: 311.738485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:04.284523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.994052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-21T15:31:04.284553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:31:03.971919Z","time spent":"312.597151ms","remote":"127.0.0.1:45976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2318,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:31:04.28456Z","caller":"traceutil/trace.go:171","msg":"trace[196276772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:938; }","duration":"125.106824ms","start":"2026-05-21T15:31:04.159442Z","end":"2026-05-21T15:31:04.284549Z","steps":["trace[196276772] 'agreement among raft nodes before linearized reading' (duration: 124.908531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:04.287474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.802094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-m4jnx\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-05-21T15:31:04.28752Z","caller":"traceutil/trace.go:171","msg":"trace[601089157] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-m4jnx; range_end:; response_count:1; response_revision:939; }","duration":"116.891785ms","start":"2026-05-21T15:31:04.170617Z","end":"2026-05-21T15:31:04.287508Z","steps":["trace[601089157] 'agreement among raft nodes before linearized reading' (duration: 116.760773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:10.864399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.895044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-2gc5g\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-05-21T15:31:10.864477Z","caller":"traceutil/trace.go:171","msg":"trace[808016232] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-2gc5g; range_end:; response_count:1; response_revision:1022; }","duration":"198.021445ms","start":"2026-05-21T15:31:10.666434Z","end":"2026-05-21T15:31:10.864455Z","steps":["trace[808016232] 'range keys from in-memory index tree' (duration: 197.6979ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:11.696297Z","caller":"traceutil/trace.go:171","msg":"trace[573320318] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"150.47222ms","start":"2026-05-21T15:31:11.545803Z","end":"2026-05-21T15:31:11.696275Z","steps":["trace[573320318] 'process raft request' (duration: 150.298507ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:11.940295Z","caller":"traceutil/trace.go:171","msg":"trace[971729490] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"169.027342ms","start":"2026-05-21T15:31:11.771243Z","end":"2026-05-21T15:31:11.94027Z","steps":["trace[971729490] 'process raft request' (duration: 158.599921ms)","trace[971729490] 'compare' (duration: 10.308159ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:31:12.274358Z","caller":"traceutil/trace.go:171","msg":"trace[723147819] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"327.487115ms","start":"2026-05-21T15:31:11.946843Z","end":"2026-05-21T15:31:12.27433Z","steps":["trace[723147819] 'process raft request' (duration: 306.065935ms)","trace[723147819] 'compare' (duration: 21.273687ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:31:12.274516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:31:11.946817Z","time spent":"327.620766ms","remote":"127.0.0.1:56530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:31:12.560917Z","caller":"traceutil/trace.go:171","msg":"trace[1275374323] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"278.87092ms","start":"2026-05-21T15:31:12.282024Z","end":"2026-05-21T15:31:12.560895Z","steps":["trace[1275374323] 'process raft request' (duration: 277.032259ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:12.562154Z","caller":"traceutil/trace.go:171","msg":"trace[2074303567] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1082; }","duration":"141.42711ms","start":"2026-05-21T15:31:12.420706Z","end":"2026-05-21T15:31:12.562133Z","steps":["trace[2074303567] 'read index received' (duration: 138.464929ms)","trace[2074303567] 'applied index is now lower than readState.Index' (duration: 2.961411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:31:12.562319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.604993ms","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-21T15:31:12.562353Z","caller":"traceutil/trace.go:171","msg":"trace[1700337647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1042; }","duration":"141.686603ms","start":"2026-05-21T15:31:12.420657Z","end":"2026-05-21T15:31:12.562343Z","steps":["trace[1700337647] 'agreement among raft nodes before linearized reading' (duration: 141.554381ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:12.562531Z","caller":"traceutil/trace.go:171","msg":"trace[444125526] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"245.24767ms","start":"2026-05-21T15:31:12.317078Z","end":"2026-05-21T15:31:12.562326Z","steps":["trace[444125526] 'process raft request' (duration: 244.958405ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:13.293567Z","caller":"traceutil/trace.go:171","msg":"trace[785577729] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1086; }","duration":"121.86025ms","start":"2026-05-21T15:31:13.171687Z","end":"2026-05-21T15:31:13.293547Z","steps":["trace[785577729] 'read index received' (duration: 12.199699ms)","trace[785577729] 'applied index is now lower than readState.Index' (duration: 109.659861ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:31:13.293647Z","caller":"traceutil/trace.go:171","msg":"trace[344238753] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"135.649636ms","start":"2026-05-21T15:31:13.157965Z","end":"2026-05-21T15:31:13.293614Z","steps":["trace[344238753] 'process raft request' (duration: 135.485683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:13.293719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.021902ms","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-21T15:31:13.293748Z","caller":"traceutil/trace.go:171","msg":"trace[776113082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1046; }","duration":"122.092824ms","start":"2026-05-21T15:31:13.171649Z","end":"2026-05-21T15:31:13.293742Z","steps":["trace[776113082] 'agreement among raft nodes before linearized reading' (duration: 121.981422ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:23.576205Z","caller":"traceutil/trace.go:171","msg":"trace[1753756517] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"126.703157ms","start":"2026-05-21T15:31:23.449484Z","end":"2026-05-21T15:31:23.576187Z","steps":["trace[1753756517] 'read index received' (duration: 126.533335ms)","trace[1753756517] 'applied index is now lower than readState.Index' (duration: 169.142µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:31:23.576361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.859761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T15:31:23.576384Z","caller":"traceutil/trace.go:171","msg":"trace[1941967303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1080; }","duration":"126.920471ms","start":"2026-05-21T15:31:23.449456Z","end":"2026-05-21T15:31:23.576376Z","steps":["trace[1941967303] 'agreement among raft nodes before linearized reading' (duration: 126.800669ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:23.576602Z","caller":"traceutil/trace.go:171","msg":"trace[1751660452] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"130.145833ms","start":"2026-05-21T15:31:23.446412Z","end":"2026-05-21T15:31:23.576557Z","steps":["trace[1751660452] 'process raft request' (duration: 129.668686ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:35.479549Z","caller":"traceutil/trace.go:171","msg":"trace[691178732] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"159.055567ms","start":"2026-05-21T15:31:35.320456Z","end":"2026-05-21T15:31:35.479511Z","steps":["trace[691178732] 'process raft request' (duration: 158.721472ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:35.585046Z","caller":"traceutil/trace.go:171","msg":"trace[1068784826] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"100.960807ms","start":"2026-05-21T15:31:35.484042Z","end":"2026-05-21T15:31:35.585003Z","steps":["trace[1068784826] 'process raft request' (duration: 80.90922ms)","trace[1068784826] 'compare' (duration: 19.784153ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:31:36.010489Z","caller":"traceutil/trace.go:171","msg":"trace[1854144696] linearizableReadLoop","detail":"{readStateIndex:1170; appliedIndex:1169; }","duration":"239.742503ms","start":"2026-05-21T15:31:35.770722Z","end":"2026-05-21T15:31:36.010465Z","steps":["trace[1854144696] 'read index received' (duration: 239.58371ms)","trace[1854144696] 'applied index is now lower than readState.Index' (duration: 158.113µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:31:36.010587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.853385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:31:36.010517Z","caller":"traceutil/trace.go:171","msg":"trace[914307517] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"279.959129ms","start":"2026-05-21T15:31:35.730545Z","end":"2026-05-21T15:31:36.010504Z","steps":["trace[914307517] 'process raft request' (duration: 279.790237ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:36.010611Z","caller":"traceutil/trace.go:171","msg":"trace[131396054] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1123; }","duration":"239.947726ms","start":"2026-05-21T15:31:35.770655Z","end":"2026-05-21T15:31:36.010603Z","steps":["trace[131396054] 'agreement among raft nodes before linearized reading' (duration: 239.886705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:36.010697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.337319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T15:31:36.010725Z","caller":"traceutil/trace.go:171","msg":"trace[58097285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1123; }","duration":"185.40286ms","start":"2026-05-21T15:31:35.825314Z","end":"2026-05-21T15:31:36.010717Z","steps":["trace[58097285] 'agreement among raft nodes before linearized reading' (duration: 185.282859ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:46.316763Z","caller":"traceutil/trace.go:171","msg":"trace[2019349064] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"106.438377ms","start":"2026-05-21T15:31:46.210294Z","end":"2026-05-21T15:31:46.316732Z","steps":["trace[2019349064] 'process raft request' (duration: 69.610558ms)","trace[2019349064] 'compare' (duration: 36.488834ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:31:48.328814Z","caller":"traceutil/trace.go:171","msg":"trace[2126133636] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"114.874117ms","start":"2026-05-21T15:31:48.213907Z","end":"2026-05-21T15:31:48.328781Z","steps":["trace[2126133636] 'process raft request' (duration: 114.681394ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:48.502679Z","caller":"traceutil/trace.go:171","msg":"trace[1817107790] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"169.449588ms","start":"2026-05-21T15:31:48.33318Z","end":"2026-05-21T15:31:48.50263Z","steps":["trace[1817107790] 'process raft request' (duration: 137.516394ms)","trace[1817107790] 'compare' (duration: 31.606549ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:31:50.486313Z","caller":"traceutil/trace.go:171","msg":"trace[2007206854] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"150.243343ms","start":"2026-05-21T15:31:50.336047Z","end":"2026-05-21T15:31:50.48629Z","steps":["trace[2007206854] 'process raft request' (duration: 150.103301ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:55.119274Z","caller":"traceutil/trace.go:171","msg":"trace[1080345418] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"115.938439ms","start":"2026-05-21T15:31:55.003283Z","end":"2026-05-21T15:31:55.119221Z","steps":["trace[1080345418] 'process raft request' (duration: 115.746326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:55.994176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.732823ms","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-21T15:31:55.994245Z","caller":"traceutil/trace.go:171","msg":"trace[1741921721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1187; }","duration":"153.832224ms","start":"2026-05-21T15:31:55.840391Z","end":"2026-05-21T15:31:55.994223Z","steps":["trace[1741921721] 'range keys from in-memory index tree' (duration: 153.56366ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:01.134921Z","caller":"traceutil/trace.go:171","msg":"trace[950697407] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"135.424325ms","start":"2026-05-21T15:32:00.999478Z","end":"2026-05-21T15:32:01.134903Z","steps":["trace[950697407] 'process raft request' (duration: 135.309743ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:26.493378Z","caller":"traceutil/trace.go:171","msg":"trace[1277117961] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"161.712274ms","start":"2026-05-21T15:32:26.331637Z","end":"2026-05-21T15:32:26.493349Z","steps":["trace[1277117961] 'read index received' (duration: 84.942254ms)","trace[1277117961] 'applied index is now lower than readState.Index' (duration: 76.76868ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:32:26.493566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.910987ms","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-21T15:32:26.493609Z","caller":"traceutil/trace.go:171","msg":"trace[1722660950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1286; }","duration":"161.983088ms","start":"2026-05-21T15:32:26.331612Z","end":"2026-05-21T15:32:26.493595Z","steps":["trace[1722660950] 'agreement among raft nodes before linearized reading' (duration: 161.834926ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:42.116238Z","caller":"traceutil/trace.go:171","msg":"trace[1873611129] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"109.172099ms","start":"2026-05-21T15:32:42.007041Z","end":"2026-05-21T15:32:42.116213Z","steps":["trace[1873611129] 'process raft request' (duration: 109.042398ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:50.099133Z","caller":"traceutil/trace.go:171","msg":"trace[1294572298] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"171.789788ms","start":"2026-05-21T15:32:49.927318Z","end":"2026-05-21T15:32:50.099108Z","steps":["trace[1294572298] 'process raft request' (duration: 171.649916ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:50.259127Z","caller":"traceutil/trace.go:171","msg":"trace[506853531] linearizableReadLoop","detail":"{readStateIndex:1467; appliedIndex:1466; }","duration":"112.014404ms","start":"2026-05-21T15:32:50.14708Z","end":"2026-05-21T15:32:50.259094Z","steps":["trace[506853531] 'read index received' (duration: 84.168242ms)","trace[506853531] 'applied index is now lower than readState.Index' (duration: 27.845232ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:32:50.259158Z","caller":"traceutil/trace.go:171","msg":"trace[901988260] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"146.267614ms","start":"2026-05-21T15:32:50.112866Z","end":"2026-05-21T15:32:50.259134Z","steps":["trace[901988260] 'process raft request' (duration: 118.500124ms)","trace[901988260] 'compare' (duration: 27.616849ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:32:50.259343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.254248ms","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-21T15:32:50.259403Z","caller":"traceutil/trace.go:171","msg":"trace[1753454793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1403; }","duration":"112.364389ms","start":"2026-05-21T15:32:50.147026Z","end":"2026-05-21T15:32:50.25939Z","steps":["trace[1753454793] 'agreement among raft nodes before linearized reading' (duration: 112.164576ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:51.130155Z","caller":"traceutil/trace.go:171","msg":"trace[1220715466] linearizableReadLoop","detail":"{readStateIndex:1469; appliedIndex:1468; }","duration":"381.032781ms","start":"2026-05-21T15:32:50.748991Z","end":"2026-05-21T15:32:51.130024Z","steps":["trace[1220715466] 'read index received' (duration: 380.874109ms)","trace[1220715466] 'applied index is now lower than readState.Index' (duration: 157.732µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:32:51.130159Z","caller":"traceutil/trace.go:171","msg":"trace[1188586797] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"467.693495ms","start":"2026-05-21T15:32:50.662435Z","end":"2026-05-21T15:32:51.130128Z","steps":["trace[1188586797] 'process raft request' (duration: 467.429201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:32:51.130434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.472172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T15:32:51.130478Z","caller":"traceutil/trace.go:171","msg":"trace[970745739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1405; }","duration":"220.560873ms","start":"2026-05-21T15:32:50.909906Z","end":"2026-05-21T15:32:51.130466Z","steps":["trace[970745739] 'agreement among raft nodes before linearized reading' (duration: 220.388391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:32:51.130445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:32:50.66241Z","time spent":"467.953367ms","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:32:51.130517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.197312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.174\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-21T15:32:51.130603Z","caller":"traceutil/trace.go:171","msg":"trace[1927313018] range","detail":"{range_begin:/registry/masterleases/199.19.213.174; range_end:; response_count:1; response_revision:1405; }","duration":"230.368013ms","start":"2026-05-21T15:32:50.90022Z","end":"2026-05-21T15:32:51.130589Z","steps":["trace[1927313018] 'agreement among raft nodes before linearized reading' (duration: 230.09866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:32:51.130474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.758052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T15:32:51.130743Z","caller":"traceutil/trace.go:171","msg":"trace[1969263740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1405; }","duration":"149.055006ms","start":"2026-05-21T15:32:50.98164Z","end":"2026-05-21T15:32:51.130695Z","steps":["trace[1969263740] 'agreement among raft nodes before linearized reading' (duration: 148.64188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:32:51.130566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.299044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:32:51.130982Z","caller":"traceutil/trace.go:171","msg":"trace[970704327] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1405; }","duration":"381.885152ms","start":"2026-05-21T15:32:50.748931Z","end":"2026-05-21T15:32:51.130816Z","steps":["trace[970704327] 'agreement among raft nodes before linearized reading' (duration: 381.280765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:32:51.131196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:32:50.74891Z","time spent":"382.151645ms","remote":"127.0.0.1:54084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-05-21T15:32:52.003404Z","caller":"traceutil/trace.go:171","msg":"trace[204780688] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"125.512466ms","start":"2026-05-21T15:32:51.877866Z","end":"2026-05-21T15:32:52.003379Z","steps":["trace[204780688] 'process raft request' (duration: 125.311074ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:33:19.465286Z","caller":"traceutil/trace.go:171","msg":"trace[1872006116] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"116.411156ms","start":"2026-05-21T15:33:19.348853Z","end":"2026-05-21T15:33:19.465265Z","steps":["trace[1872006116] 'process raft request' (duration: 16.776656ms)","trace[1872006116] 'compare' (duration: 99.492917ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:33:19.46544Z","caller":"traceutil/trace.go:171","msg":"trace[483456292] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"114.928231ms","start":"2026-05-21T15:33:19.350496Z","end":"2026-05-21T15:33:19.465425Z","steps":["trace[483456292] 'process raft request' (duration: 114.741196ms)"],"step_count":1}