{"level":"warn","ts":"2026-04-28T06:05:38.627133Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:05:38.62724Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-04-28T06:05:38.627324Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:05:38.627342Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-04-28T06:05:38.627368Z","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-04-28T06:05:38.62832Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-04-28T06:05:38.628422Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-04-28T06:05:38.632343Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.637221ms"} {"level":"info","ts":"2026-04-28T06:05:38.639025Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-04-28T06:05:38.639111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T06:05:38.639131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-04-28T06:05:38.639144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T06:05:38.639175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-04-28T06:05:38.639212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-04-28T06:05:38.647785Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T06:05:38.649351Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T06:05:38.651875Z","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-04-28T06:05:38.657774Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T06:05:38.658029Z","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-04-28T06:05:38.658102Z","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-04-28T06:05:38.658114Z","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-04-28T06:05:38.658121Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T06:05:38.659895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-04-28T06:05:38.661555Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-04-28T06:05:38.662836Z","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-04-28T06:05:38.663052Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-04-28T06:05:38.663085Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-04-28T06:05:38.663377Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T06:05:38.663417Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T06:05:39.639682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T06:05:39.63979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T06:05:39.639863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-04-28T06:05:39.639892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-04-28T06:05:39.639905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-04-28T06:05:39.639926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-04-28T06:05:39.63994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-04-28T06:05:40.03366Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T06:05:40.033952Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:05:40.0341Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:05:40.034386Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T06:05:40.034479Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T06:05:40.03425Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:05:40.037837Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T06:05:40.038391Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-04-28T06:05:40.066735Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:05:40.066979Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:05:40.067077Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-28T06:05:42.921544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366370255,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:05:43.243189Z","caller":"traceutil/trace.go:171","msg":"trace[1921126251] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"822.338913ms","start":"2026-04-28T06:05:42.420822Z","end":"2026-04-28T06:05:43.243161Z","steps":["trace[1921126251] 'read index received' (duration: 821.993804ms)","trace[1921126251] 'applied index is now lower than readState.Index' (duration: 343.269µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:05:43.243264Z","caller":"traceutil/trace.go:171","msg":"trace[1035323000] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"823.501415ms","start":"2026-04-28T06:05:42.419731Z","end":"2026-04-28T06:05:43.243233Z","steps":["trace[1035323000] 'process raft request' (duration: 823.193497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:43.243577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.690413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:05:43.243681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.877783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:05:43.243847Z","caller":"traceutil/trace.go:171","msg":"trace[408175547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"804.16355ms","start":"2026-04-28T06:05:42.439665Z","end":"2026-04-28T06:05:43.243829Z","steps":["trace[408175547] 'agreement among raft nodes before linearized reading' (duration: 803.7526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:43.24384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:42.419714Z","time spent":"823.689431ms","remote":"127.0.0.1:55088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:05:43.244067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:42.439643Z","time spent":"804.405058ms","remote":"127.0.0.1:54758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:05:43.243713Z","caller":"traceutil/trace.go:171","msg":"trace[1150537082] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:71; }","duration":"822.930239ms","start":"2026-04-28T06:05:42.420766Z","end":"2026-04-28T06:05:43.243696Z","steps":["trace[1150537082] 'agreement among raft nodes before linearized reading' (duration: 822.60106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:43.244238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:42.420748Z","time spent":"823.470135ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-04-28T06:05:43.782148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.040274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:05:43.782251Z","caller":"traceutil/trace.go:171","msg":"trace[1521069743] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:71; }","duration":"533.184708ms","start":"2026-04-28T06:05:43.249044Z","end":"2026-04-28T06:05:43.782229Z","steps":["trace[1521069743] 'range keys from in-memory index tree' (duration: 532.977162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:43.782299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:43.249036Z","time spent":"533.254039ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"info","ts":"2026-04-28T06:05:43.782449Z","caller":"traceutil/trace.go:171","msg":"trace[1972491306] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"342.100121ms","start":"2026-04-28T06:05:43.440318Z","end":"2026-04-28T06:05:43.782419Z","steps":["trace[1972491306] 'read index received' (duration: 337.310478ms)","trace[1972491306] 'applied index is now lower than readState.Index' (duration: 4.788063ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:05:43.782568Z","caller":"traceutil/trace.go:171","msg":"trace[1761821688] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"533.133187ms","start":"2026-04-28T06:05:43.249402Z","end":"2026-04-28T06:05:43.782535Z","steps":["trace[1761821688] 'process raft request' (duration: 528.18612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:43.782718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:43.249389Z","time spent":"533.255501ms","remote":"127.0.0.1:55088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:05:43.782725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.410749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:05:43.782864Z","caller":"traceutil/trace.go:171","msg":"trace[1168850432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"342.614794ms","start":"2026-04-28T06:05:43.440235Z","end":"2026-04-28T06:05:43.78285Z","steps":["trace[1168850432] 'agreement among raft nodes before linearized reading' (duration: 342.314306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:43.782965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:43.440213Z","time spent":"342.739097ms","remote":"127.0.0.1:54758","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-04-28T06:05:44.987723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.116346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-28T06:05:44.987867Z","caller":"traceutil/trace.go:171","msg":"trace[1596613645] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"647.871511ms","start":"2026-04-28T06:05:44.339977Z","end":"2026-04-28T06:05:44.987848Z","steps":["trace[1596613645] 'read index received' (duration: 128.274552ms)","trace[1596613645] 'applied index is now lower than readState.Index' (duration: 519.595559ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:05:44.987962Z","caller":"traceutil/trace.go:171","msg":"trace[650042509] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"1.190365014s","start":"2026-04-28T06:05:43.797585Z","end":"2026-04-28T06:05:44.98795Z","steps":["trace[650042509] 'process raft request' (duration: 670.775924ms)","trace[650042509] 'compare' (duration: 518.935352ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:05:44.988026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:43.797565Z","time spent":"1.190434056s","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:05:44.988362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.374885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:05:44.988404Z","caller":"traceutil/trace.go:171","msg":"trace[545362542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"648.451727ms","start":"2026-04-28T06:05:44.339942Z","end":"2026-04-28T06:05:44.988393Z","steps":["trace[545362542] 'agreement among raft nodes before linearized reading' (duration: 648.282003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:44.988436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:44.339921Z","time spent":"648.509378ms","remote":"127.0.0.1:54744","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-04-28T06:05:44.988681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.843017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:05:44.988789Z","caller":"traceutil/trace.go:171","msg":"trace[295143150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"406.001551ms","start":"2026-04-28T06:05:44.582759Z","end":"2026-04-28T06:05:44.988761Z","steps":["trace[295143150] 'agreement among raft nodes before linearized reading' (duration: 405.845567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:44.988844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:44.582733Z","time spent":"406.098504ms","remote":"127.0.0.1:54758","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-04-28T06:05:45.30689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.967519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-28T06:05:45.306994Z","caller":"traceutil/trace.go:171","msg":"trace[603176668] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"311.273017ms","start":"2026-04-28T06:05:44.995703Z","end":"2026-04-28T06:05:45.306976Z","steps":["trace[603176668] 'process raft request' (duration: 92.145834ms)","trace[603176668] 'compare' (duration: 218.6487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:05:45.307044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:44.995685Z","time spent":"311.339298ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:06:15.567266Z","caller":"traceutil/trace.go:171","msg":"trace[707072075] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"135.368788ms","start":"2026-04-28T06:06:15.43103Z","end":"2026-04-28T06:06:15.566399Z","steps":["trace[707072075] 'read index received' (duration: 135.096361ms)","trace[707072075] 'applied index is now lower than readState.Index' (duration: 271.517µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:15.567143Z","caller":"traceutil/trace.go:171","msg":"trace[320201091] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"165.81778ms","start":"2026-04-28T06:06:15.400708Z","end":"2026-04-28T06:06:15.566526Z","steps":["trace[320201091] 'process raft request' (duration: 165.538732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:15.569284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.233828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:15.569381Z","caller":"traceutil/trace.go:171","msg":"trace[1638870640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"138.351011ms","start":"2026-04-28T06:06:15.430986Z","end":"2026-04-28T06:06:15.569337Z","steps":["trace[1638870640] 'agreement among raft nodes before linearized reading' (duration: 135.923755ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:19.81654Z","caller":"traceutil/trace.go:171","msg":"trace[460422614] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"519.363614ms","start":"2026-04-28T06:06:19.297147Z","end":"2026-04-28T06:06:19.81651Z","steps":["trace[460422614] 'process raft request' (duration: 519.219881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:19.81672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:19.297129Z","time spent":"519.512388ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:06:19.823638Z","caller":"traceutil/trace.go:171","msg":"trace[211666347] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"511.275271ms","start":"2026-04-28T06:06:19.312337Z","end":"2026-04-28T06:06:19.823612Z","steps":["trace[211666347] 'process raft request' (duration: 510.767826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:19.823776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:19.312328Z","time spent":"511.391094ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:06:19.823813Z","caller":"traceutil/trace.go:171","msg":"trace[1278142826] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:547; }","duration":"397.374604ms","start":"2026-04-28T06:06:19.426414Z","end":"2026-04-28T06:06:19.823788Z","steps":["trace[1278142826] 'read index received' (duration: 389.987901ms)","trace[1278142826] 'applied index is now lower than readState.Index' (duration: 7.385823ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:19.823958Z","caller":"traceutil/trace.go:171","msg":"trace[1643437820] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"509.225464ms","start":"2026-04-28T06:06:19.314722Z","end":"2026-04-28T06:06:19.823947Z","steps":["trace[1643437820] 'process raft request' (duration: 508.419211ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:19.824006Z","caller":"traceutil/trace.go:171","msg":"trace[41443170] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"523.806648ms","start":"2026-04-28T06:06:19.300183Z","end":"2026-04-28T06:06:19.82399Z","steps":["trace[41443170] 'process raft request' (duration: 522.723787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:19.824056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:19.314714Z","time spent":"509.280556ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:06:19.824083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:19.300175Z","time spent":"523.878279ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:06:19.824183Z","caller":"traceutil/trace.go:171","msg":"trace[328126521] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"508.71351ms","start":"2026-04-28T06:06:19.315461Z","end":"2026-04-28T06:06:19.824174Z","steps":["trace[328126521] 'process raft request' (duration: 507.711972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:19.824232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:19.315452Z","time spent":"508.760001ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:06:19.824244Z","caller":"traceutil/trace.go:171","msg":"trace[1689709942] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"522.128801ms","start":"2026-04-28T06:06:19.302101Z","end":"2026-04-28T06:06:19.82423Z","steps":["trace[1689709942] 'process raft request' (duration: 520.924307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:19.824352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:19.302092Z","time spent":"522.227753ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:06:19.824399Z","caller":"traceutil/trace.go:171","msg":"trace[1232798448] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"505.981364ms","start":"2026-04-28T06:06:19.318408Z","end":"2026-04-28T06:06:19.82439Z","steps":["trace[1232798448] 'process raft request' (duration: 504.801001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:19.824444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:19.318399Z","time spent":"506.024666ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:06:19.824481Z","caller":"traceutil/trace.go:171","msg":"trace[1030239424] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"513.374198ms","start":"2026-04-28T06:06:19.311097Z","end":"2026-04-28T06:06:19.824471Z","steps":["trace[1030239424] 'process raft request' (duration: 511.970039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:19.82591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:19.311086Z","time spent":"514.753606ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:06:19.824608Z","caller":"traceutil/trace.go:171","msg":"trace[989202246] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"493.022596ms","start":"2026-04-28T06:06:19.331568Z","end":"2026-04-28T06:06:19.82459Z","steps":["trace[989202246] 'process raft request' (duration: 491.68291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:19.826593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:19.331552Z","time spent":"494.910138ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:06:19.824658Z","caller":"traceutil/trace.go:171","msg":"trace[198628467] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"475.949764ms","start":"2026-04-28T06:06:19.348698Z","end":"2026-04-28T06:06:19.824648Z","steps":["trace[198628467] 'process raft request' (duration: 474.613898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:19.826808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:19.348681Z","time spent":"478.102655ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:06:19.824704Z","caller":"traceutil/trace.go:171","msg":"trace[1376183661] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"446.78069ms","start":"2026-04-28T06:06:19.377904Z","end":"2026-04-28T06:06:19.824685Z","steps":["trace[1376183661] 'process raft request' (duration: 445.752811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:19.826929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:19.377885Z","time spent":"449.010042ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:06:19.824739Z","caller":"traceutil/trace.go:171","msg":"trace[1930152597] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"336.616577ms","start":"2026-04-28T06:06:19.488113Z","end":"2026-04-28T06:06:19.824729Z","steps":["trace[1930152597] 'process raft request' (duration: 335.622089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:19.827052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:19.488082Z","time spent":"338.92162ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:06:19.824826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.409813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:19.827171Z","caller":"traceutil/trace.go:171","msg":"trace[533336131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"400.780448ms","start":"2026-04-28T06:06:19.426359Z","end":"2026-04-28T06:06:19.827139Z","steps":["trace[533336131] 'agreement among raft nodes before linearized reading' (duration: 398.400843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:19.82722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:19.426339Z","time spent":"400.866661ms","remote":"127.0.0.1:54758","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-04-28T06:06:19.824898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.409941ms","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-04-28T06:06:19.827295Z","caller":"traceutil/trace.go:171","msg":"trace[1730452155] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"241.827898ms","start":"2026-04-28T06:06:19.58545Z","end":"2026-04-28T06:06:19.827278Z","steps":["trace[1730452155] 'agreement among raft nodes before linearized reading' (duration: 239.404801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:20.077627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.757772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:06:20.077882Z","caller":"traceutil/trace.go:171","msg":"trace[678710526] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"234.745863ms","start":"2026-04-28T06:06:19.84312Z","end":"2026-04-28T06:06:20.077866Z","steps":["trace[678710526] 'process raft request' (duration: 234.677541ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:20.078036Z","caller":"traceutil/trace.go:171","msg":"trace[23729000] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"235.839943ms","start":"2026-04-28T06:06:19.842172Z","end":"2026-04-28T06:06:20.078012Z","steps":["trace[23729000] 'process raft request' (duration: 104.633769ms)","trace[23729000] 'compare' (duration: 130.645679ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:20.078049Z","caller":"traceutil/trace.go:171","msg":"trace[582397059] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"235.773171ms","start":"2026-04-28T06:06:19.842259Z","end":"2026-04-28T06:06:20.078032Z","steps":["trace[582397059] 'process raft request' (duration: 235.452973ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:20.078149Z","caller":"traceutil/trace.go:171","msg":"trace[520774382] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"235.033622ms","start":"2026-04-28T06:06:19.843105Z","end":"2026-04-28T06:06:20.078139Z","steps":["trace[520774382] 'process raft request' (duration: 234.662371ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:20.078454Z","caller":"traceutil/trace.go:171","msg":"trace[1825308809] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"232.474841ms","start":"2026-04-28T06:06:19.845968Z","end":"2026-04-28T06:06:20.078443Z","steps":["trace[1825308809] 'process raft request' (duration: 231.865784ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:20.078519Z","caller":"traceutil/trace.go:171","msg":"trace[2015631914] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"227.419202ms","start":"2026-04-28T06:06:19.851086Z","end":"2026-04-28T06:06:20.078505Z","steps":["trace[2015631914] 'process raft request' (duration: 227.35296ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:20.07872Z","caller":"traceutil/trace.go:171","msg":"trace[1826004969] transaction","detail":"{read_only:false; number_of_response:0; response_revision:552; }","duration":"227.539444ms","start":"2026-04-28T06:06:19.851171Z","end":"2026-04-28T06:06:20.07871Z","steps":["trace[1826004969] 'process raft request' (duration: 227.309838ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:20.078753Z","caller":"traceutil/trace.go:171","msg":"trace[838701001] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"228.618394ms","start":"2026-04-28T06:06:19.850128Z","end":"2026-04-28T06:06:20.078746Z","steps":["trace[838701001] 'process raft request' (duration: 228.272925ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:20.082265Z","caller":"traceutil/trace.go:171","msg":"trace[1475801071] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"174.486639ms","start":"2026-04-28T06:06:19.907761Z","end":"2026-04-28T06:06:20.082247Z","steps":["trace[1475801071] 'process raft request' (duration: 174.092108ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:20.08262Z","caller":"traceutil/trace.go:171","msg":"trace[1799042328] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"226.223008ms","start":"2026-04-28T06:06:19.856385Z","end":"2026-04-28T06:06:20.082608Z","steps":["trace[1799042328] 'process raft request' (duration: 222.201767ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:20.082765Z","caller":"traceutil/trace.go:171","msg":"trace[352625885] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"198.816931ms","start":"2026-04-28T06:06:19.883915Z","end":"2026-04-28T06:06:20.082732Z","steps":["trace[352625885] 'process raft request' (duration: 194.819831ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:20.083087Z","caller":"traceutil/trace.go:171","msg":"trace[224609445] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"212.397797ms","start":"2026-04-28T06:06:19.870672Z","end":"2026-04-28T06:06:20.08307Z","steps":["trace[224609445] 'process raft request' (duration: 208.010095ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:20.078723Z","caller":"traceutil/trace.go:171","msg":"trace[1385068213] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"230.099965ms","start":"2026-04-28T06:06:19.848613Z","end":"2026-04-28T06:06:20.078713Z","steps":["trace[1385068213] 'process raft request' (duration: 229.717465ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:20.086317Z","caller":"traceutil/trace.go:171","msg":"trace[1424755518] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:565; }","duration":"142.517175ms","start":"2026-04-28T06:06:19.943767Z","end":"2026-04-28T06:06:20.086284Z","steps":["trace[1424755518] 'read index received' (duration: 3.106005ms)","trace[1424755518] 'applied index is now lower than readState.Index' (duration: 139.40952ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:06:20.086454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.672589ms","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-04-28T06:06:20.086491Z","caller":"traceutil/trace.go:171","msg":"trace[936997351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"142.732581ms","start":"2026-04-28T06:06:19.943745Z","end":"2026-04-28T06:06:20.086478Z","steps":["trace[936997351] 'agreement among raft nodes before linearized reading' (duration: 142.603868ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:21.691965Z","caller":"traceutil/trace.go:171","msg":"trace[946614390] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"763.471238ms","start":"2026-04-28T06:06:20.92847Z","end":"2026-04-28T06:06:21.691941Z","steps":["trace[946614390] 'process raft request' (duration: 763.033455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.692633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:20.92842Z","time spent":"763.618121ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:06:21.95074Z","caller":"traceutil/trace.go:171","msg":"trace[1707173382] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"524.781023ms","start":"2026-04-28T06:06:21.425932Z","end":"2026-04-28T06:06:21.950713Z","steps":["trace[1707173382] 'read index received' (duration: 265.806661ms)","trace[1707173382] 'applied index is now lower than readState.Index' (duration: 258.973342ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:21.951035Z","caller":"traceutil/trace.go:171","msg":"trace[2104390203] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"957.158796ms","start":"2026-04-28T06:06:20.99386Z","end":"2026-04-28T06:06:21.951018Z","steps":["trace[2104390203] 'process raft request' (duration: 955.263814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.951131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.183916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:06:21.951146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:20.993825Z","time spent":"957.252269ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:06:21.951192Z","caller":"traceutil/trace.go:171","msg":"trace[520714746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"525.288178ms","start":"2026-04-28T06:06:21.425888Z","end":"2026-04-28T06:06:21.951177Z","steps":["trace[520714746] 'agreement among raft nodes before linearized reading' (duration: 525.138904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.951222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:21.425871Z","time spent":"525.34643ms","remote":"127.0.0.1:54758","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-04-28T06:06:21.953856Z","caller":"traceutil/trace.go:171","msg":"trace[1053942562] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"200.997801ms","start":"2026-04-28T06:06:21.752833Z","end":"2026-04-28T06:06:21.953831Z","steps":["trace[1053942562] 'process raft request' (duration: 200.814616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.954312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.678263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:21.954363Z","caller":"traceutil/trace.go:171","msg":"trace[894207164] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:602; }","duration":"162.742856ms","start":"2026-04-28T06:06:21.791606Z","end":"2026-04-28T06:06:21.954349Z","steps":["trace[894207164] 'agreement among raft nodes before linearized reading' (duration: 162.662362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.954361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.283216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:21.954431Z","caller":"traceutil/trace.go:171","msg":"trace[2098773605] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:602; }","duration":"290.37278ms","start":"2026-04-28T06:06:21.664039Z","end":"2026-04-28T06:06:21.954412Z","steps":["trace[2098773605] 'agreement among raft nodes before linearized reading' (duration: 290.281387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.954515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.166392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:21.954549Z","caller":"traceutil/trace.go:171","msg":"trace[350280155] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:602; }","duration":"291.202863ms","start":"2026-04-28T06:06:21.663334Z","end":"2026-04-28T06:06:21.954537Z","steps":["trace[350280155] 'agreement among raft nodes before linearized reading' (duration: 291.152461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.954632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.259669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:21.954665Z","caller":"traceutil/trace.go:171","msg":"trace[1758114264] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:602; }","duration":"163.321671ms","start":"2026-04-28T06:06:21.791334Z","end":"2026-04-28T06:06:21.954655Z","steps":["trace[1758114264] 'agreement among raft nodes before linearized reading' (duration: 163.270789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.954697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.410188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:21.95473Z","caller":"traceutil/trace.go:171","msg":"trace[2112455480] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:602; }","duration":"291.48269ms","start":"2026-04-28T06:06:21.663238Z","end":"2026-04-28T06:06:21.954721Z","steps":["trace[2112455480] 'agreement among raft nodes before linearized reading' (duration: 291.419739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.954804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.245664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:21.954838Z","caller":"traceutil/trace.go:171","msg":"trace[938682584] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:602; }","duration":"236.298316ms","start":"2026-04-28T06:06:21.718527Z","end":"2026-04-28T06:06:21.954825Z","steps":["trace[938682584] 'agreement among raft nodes before linearized reading' (duration: 236.253135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.954951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.860569ms","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-04-28T06:06:21.954973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.468151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:21.954999Z","caller":"traceutil/trace.go:171","msg":"trace[830689319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"111.958131ms","start":"2026-04-28T06:06:21.843026Z","end":"2026-04-28T06:06:21.954984Z","steps":["trace[830689319] 'agreement among raft nodes before linearized reading' (duration: 111.876539ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:21.955005Z","caller":"traceutil/trace.go:171","msg":"trace[1124127275] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:602; }","duration":"236.500952ms","start":"2026-04-28T06:06:21.718495Z","end":"2026-04-28T06:06:21.954996Z","steps":["trace[1124127275] 'agreement among raft nodes before linearized reading' (duration: 236.45617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.955075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.055024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:06:21.955117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.716239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:06:21.955142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.756283ms","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-04-28T06:06:21.955152Z","caller":"traceutil/trace.go:171","msg":"trace[369460828] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:602; }","duration":"236.78443ms","start":"2026-04-28T06:06:21.718359Z","end":"2026-04-28T06:06:21.955144Z","steps":["trace[369460828] 'agreement among raft nodes before linearized reading' (duration: 236.737599ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:21.955157Z","caller":"traceutil/trace.go:171","msg":"trace[1634333251] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:602; }","duration":"100.156066ms","start":"2026-04-28T06:06:21.854964Z","end":"2026-04-28T06:06:21.95512Z","steps":["trace[1634333251] 'agreement among raft nodes before linearized reading' (duration: 100.052992ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:21.955161Z","caller":"traceutil/trace.go:171","msg":"trace[22306988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"100.821655ms","start":"2026-04-28T06:06:21.854333Z","end":"2026-04-28T06:06:21.955155Z","steps":["trace[22306988] 'agreement among raft nodes before linearized reading' (duration: 100.747083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.955487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.215618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:21.955505Z","caller":"traceutil/trace.go:171","msg":"trace[1493291133] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:602; }","duration":"129.251929ms","start":"2026-04-28T06:06:21.826247Z","end":"2026-04-28T06:06:21.955499Z","steps":["trace[1493291133] 'agreement among raft nodes before linearized reading' (duration: 129.220128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.954287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.299421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:21.955714Z","caller":"traceutil/trace.go:171","msg":"trace[1337285012] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:602; }","duration":"163.784824ms","start":"2026-04-28T06:06:21.791913Z","end":"2026-04-28T06:06:21.955698Z","steps":["trace[1337285012] 'agreement among raft nodes before linearized reading' (duration: 162.314003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:21.955495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.143456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:21.959177Z","caller":"traceutil/trace.go:171","msg":"trace[804178325] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:602; }","duration":"132.840958ms","start":"2026-04-28T06:06:21.826308Z","end":"2026-04-28T06:06:21.959149Z","steps":["trace[804178325] 'agreement among raft nodes before linearized reading' (duration: 129.157297ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:26.291287Z","caller":"traceutil/trace.go:171","msg":"trace[40442226] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"139.04049ms","start":"2026-04-28T06:06:26.152221Z","end":"2026-04-28T06:06:26.291262Z","steps":["trace[40442226] 'process raft request' (duration: 119.960343ms)","trace[40442226] 'compare' (duration: 18.973434ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:26.835691Z","caller":"traceutil/trace.go:171","msg":"trace[740005242] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"407.213268ms","start":"2026-04-28T06:06:26.428457Z","end":"2026-04-28T06:06:26.835671Z","steps":["trace[740005242] 'read index received' (duration: 406.720614ms)","trace[740005242] 'applied index is now lower than readState.Index' (duration: 491.924µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:26.835748Z","caller":"traceutil/trace.go:171","msg":"trace[1509788077] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"501.973404ms","start":"2026-04-28T06:06:26.33376Z","end":"2026-04-28T06:06:26.835733Z","steps":["trace[1509788077] 'process raft request' (duration: 501.621284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:26.835831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:26.333735Z","time spent":"502.047057ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:06:26.835848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.390702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:26.835883Z","caller":"traceutil/trace.go:171","msg":"trace[1836276523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"407.466744ms","start":"2026-04-28T06:06:26.428408Z","end":"2026-04-28T06:06:26.835875Z","steps":["trace[1836276523] 'agreement among raft nodes before linearized reading' (duration: 407.385882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:26.835908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:26.428389Z","time spent":"407.514156ms","remote":"127.0.0.1:54758","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-04-28T06:06:28.272989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264969598s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:06:28.273122Z","caller":"traceutil/trace.go:171","msg":"trace[521644976] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"1.373934407s","start":"2026-04-28T06:06:26.899169Z","end":"2026-04-28T06:06:28.273104Z","steps":["trace[521644976] 'read index received' (duration: 108.701512ms)","trace[521644976] 'applied index is now lower than readState.Index' (duration: 1.265231435s)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:28.273244Z","caller":"traceutil/trace.go:171","msg":"trace[1861234514] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.420984056s","start":"2026-04-28T06:06:26.852246Z","end":"2026-04-28T06:06:28.27323Z","steps":["trace[1861234514] 'process raft request' (duration: 155.681209ms)","trace[1861234514] 'compare' (duration: 1.263993801s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:06:28.273307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:26.852214Z","time spent":"1.421057248s","remote":"127.0.0.1:54952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:06:28.273395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327059633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:06:28.273452Z","caller":"traceutil/trace.go:171","msg":"trace[1787882908] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:627; }","duration":"1.327158007s","start":"2026-04-28T06:06:26.946278Z","end":"2026-04-28T06:06:28.273436Z","steps":["trace[1787882908] 'agreement among raft nodes before linearized reading' (duration: 1.327043713s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:28.273433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.726225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-28T06:06:28.273484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:26.946258Z","time spent":"1.327220268s","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.140\" "} {"level":"info","ts":"2026-04-28T06:06:28.273499Z","caller":"traceutil/trace.go:171","msg":"trace[1053571475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"141.829428ms","start":"2026-04-28T06:06:28.131654Z","end":"2026-04-28T06:06:28.273483Z","steps":["trace[1053571475] 'agreement among raft nodes before linearized reading' (duration: 141.673713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:28.273662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374493863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:06:28.273679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.143774ms","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-04-28T06:06:28.273695Z","caller":"traceutil/trace.go:171","msg":"trace[804563441] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:627; }","duration":"1.374549564s","start":"2026-04-28T06:06:26.899135Z","end":"2026-04-28T06:06:28.273685Z","steps":["trace[804563441] 'agreement among raft nodes before linearized reading' (duration: 1.374504293s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:28.273707Z","caller":"traceutil/trace.go:171","msg":"trace[789941841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"286.206635ms","start":"2026-04-28T06:06:27.987491Z","end":"2026-04-28T06:06:28.273698Z","steps":["trace[789941841] 'agreement among raft nodes before linearized reading' (duration: 286.117603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:28.273706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.21394ms","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-04-28T06:06:28.273717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:26.899121Z","time spent":"1.374589726s","remote":"127.0.0.1:54966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-28T06:06:28.273738Z","caller":"traceutil/trace.go:171","msg":"trace[361275569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"288.284762ms","start":"2026-04-28T06:06:27.985443Z","end":"2026-04-28T06:06:28.273728Z","steps":["trace[361275569] 'agreement among raft nodes before linearized reading' (duration: 288.19749ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:30.25841Z","caller":"traceutil/trace.go:171","msg":"trace[1184843734] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"139.02434ms","start":"2026-04-28T06:06:30.119363Z","end":"2026-04-28T06:06:30.258387Z","steps":["trace[1184843734] 'process raft request' (duration: 138.927298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:44.2776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366371775,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:06:44.429376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.250003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:06:44.429431Z","caller":"traceutil/trace.go:171","msg":"trace[296142680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"948.344785ms","start":"2026-04-28T06:06:43.481074Z","end":"2026-04-28T06:06:44.429419Z","steps":["trace[296142680] 'range keys from in-memory index tree' (duration: 947.989316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:44.42946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:43.481054Z","time spent":"948.396076ms","remote":"127.0.0.1:54758","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-04-28T06:06:44.701004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.07925ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T06:06:44.701403Z","caller":"traceutil/trace.go:171","msg":"trace[1696942039] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:834; }","duration":"924.142932ms","start":"2026-04-28T06:06:43.777199Z","end":"2026-04-28T06:06:44.701342Z","steps":["trace[1696942039] 'read index received' (duration: 640.822555ms)","trace[1696942039] 'applied index is now lower than readState.Index' (duration: 283.317817ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:44.701609Z","caller":"traceutil/trace.go:171","msg":"trace[170395380] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"779.154669ms","start":"2026-04-28T06:06:43.922255Z","end":"2026-04-28T06:06:44.70141Z","steps":["trace[170395380] 'process raft request' (duration: 778.86688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:44.701806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:43.922229Z","time spent":"779.491678ms","remote":"127.0.0.1:54996","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-04-28T06:06:44.706504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.791506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-28T06:06:44.706553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.159268ms","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-04-28T06:06:44.706554Z","caller":"traceutil/trace.go:171","msg":"trace[1859186842] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"314.854138ms","start":"2026-04-28T06:06:44.391685Z","end":"2026-04-28T06:06:44.706539Z","steps":["trace[1859186842] 'agreement among raft nodes before linearized reading' (duration: 309.917735ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:44.706584Z","caller":"traceutil/trace.go:171","msg":"trace[263880449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"311.207349ms","start":"2026-04-28T06:06:44.395368Z","end":"2026-04-28T06:06:44.706576Z","steps":["trace[263880449] 'agreement among raft nodes before linearized reading' (duration: 306.221114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:44.706593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:44.391679Z","time spent":"314.906329ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:06:44.706608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:44.395337Z","time spent":"311.26393ms","remote":"127.0.0.1:55016","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-04-28T06:06:44.70659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.955583ms","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-04-28T06:06:44.706655Z","caller":"traceutil/trace.go:171","msg":"trace[1809650233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"317.068807ms","start":"2026-04-28T06:06:44.389571Z","end":"2026-04-28T06:06:44.70664Z","steps":["trace[1809650233] 'agreement among raft nodes before linearized reading' (duration: 311.992611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:44.706688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:44.389546Z","time spent":"317.135129ms","remote":"127.0.0.1:55016","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-04-28T06:06:44.706681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.410837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:06:44.706758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.85216ms","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-04-28T06:06:44.706845Z","caller":"traceutil/trace.go:171","msg":"trace[673206036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"313.978884ms","start":"2026-04-28T06:06:44.392842Z","end":"2026-04-28T06:06:44.706821Z","steps":["trace[673206036] 'agreement among raft nodes before linearized reading' (duration: 308.753692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:44.706887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:44.392828Z","time spent":"314.049447ms","remote":"127.0.0.1:55016","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":"warn","ts":"2026-04-28T06:06:44.70692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.729803ms","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-04-28T06:06:44.706945Z","caller":"traceutil/trace.go:171","msg":"trace[290673127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"929.782804ms","start":"2026-04-28T06:06:43.777153Z","end":"2026-04-28T06:06:44.706936Z","steps":["trace[290673127] 'agreement among raft nodes before linearized reading' (duration: 924.313356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:44.706963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:06:43.777132Z","time spent":"929.827286ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T06:06:44.707644Z","caller":"traceutil/trace.go:171","msg":"trace[1690986982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"280.498841ms","start":"2026-04-28T06:06:44.426245Z","end":"2026-04-28T06:06:44.706744Z","steps":["trace[1690986982] 'agreement among raft nodes before linearized reading' (duration: 275.337001ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:50.916885Z","caller":"traceutil/trace.go:171","msg":"trace[550405113] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:904; }","duration":"121.963942ms","start":"2026-04-28T06:06:50.7949Z","end":"2026-04-28T06:06:50.916864Z","steps":["trace[550405113] 'read index received' (duration: 98.450517ms)","trace[550405113] 'applied index is now lower than readState.Index' (duration: 23.512585ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:50.916986Z","caller":"traceutil/trace.go:171","msg":"trace[132658705] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"148.17349ms","start":"2026-04-28T06:06:50.768795Z","end":"2026-04-28T06:06:50.916969Z","steps":["trace[132658705] 'process raft request' (duration: 148.025066ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:50.917013Z","caller":"traceutil/trace.go:171","msg":"trace[627147495] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"148.317453ms","start":"2026-04-28T06:06:50.768661Z","end":"2026-04-28T06:06:50.916978Z","steps":["trace[627147495] 'process raft request' (duration: 124.580522ms)","trace[627147495] 'compare' (duration: 23.449864ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:06:50.917518Z","caller":"traceutil/trace.go:171","msg":"trace[1571221985] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"120.374639ms","start":"2026-04-28T06:06:50.797119Z","end":"2026-04-28T06:06:50.917494Z","steps":["trace[1571221985] 'process raft request' (duration: 120.245756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:06:50.918235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.776026ms","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-04-28T06:06:50.918291Z","caller":"traceutil/trace.go:171","msg":"trace[523703140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:873; }","duration":"151.892809ms","start":"2026-04-28T06:06:50.766385Z","end":"2026-04-28T06:06:50.918278Z","steps":["trace[523703140] 'agreement among raft nodes before linearized reading' (duration: 150.566523ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:06:51.524151Z","caller":"traceutil/trace.go:171","msg":"trace[150153710] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"165.66389ms","start":"2026-04-28T06:06:51.358463Z","end":"2026-04-28T06:06:51.524127Z","steps":["trace[150153710] 'process raft request' (duration: 165.350692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:19.284693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.743513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:07:19.284987Z","caller":"traceutil/trace.go:171","msg":"trace[803799539] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"216.483452ms","start":"2026-04-28T06:07:19.068477Z","end":"2026-04-28T06:07:19.28496Z","steps":["trace[803799539] 'process raft request' (duration: 40.0361ms)","trace[803799539] 'compare' (duration: 175.561769ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:07:19.285187Z","caller":"traceutil/trace.go:171","msg":"trace[973826588] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"216.117384ms","start":"2026-04-28T06:07:19.069055Z","end":"2026-04-28T06:07:19.285172Z","steps":["trace[973826588] 'process raft request' (duration: 215.810555ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:19.285295Z","caller":"traceutil/trace.go:171","msg":"trace[1308592533] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"215.476155ms","start":"2026-04-28T06:07:19.069809Z","end":"2026-04-28T06:07:19.285285Z","steps":["trace[1308592533] 'process raft request' (duration: 215.113935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:19.808706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.597572ms","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-04-28T06:07:19.808807Z","caller":"traceutil/trace.go:171","msg":"trace[1693432149] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1020; }","duration":"144.806318ms","start":"2026-04-28T06:07:19.66398Z","end":"2026-04-28T06:07:19.808786Z","steps":["trace[1693432149] 'count revisions from in-memory index tree' (duration: 144.50466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:19.808842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.789835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:07:19.808937Z","caller":"traceutil/trace.go:171","msg":"trace[1820535600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"384.933869ms","start":"2026-04-28T06:07:19.423973Z","end":"2026-04-28T06:07:19.808907Z","steps":["trace[1820535600] 'range keys from in-memory index tree' (duration: 384.63771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:19.809018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:19.423933Z","time spent":"385.073803ms","remote":"127.0.0.1:54744","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-04-28T06:07:20.293521Z","caller":"traceutil/trace.go:171","msg":"trace[515185486] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"221.546349ms","start":"2026-04-28T06:07:20.071957Z","end":"2026-04-28T06:07:20.293503Z","steps":["trace[515185486] 'read index received' (duration: 221.413026ms)","trace[515185486] 'applied index is now lower than readState.Index' (duration: 132.563µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:07:20.293561Z","caller":"traceutil/trace.go:171","msg":"trace[1382761061] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"571.33465ms","start":"2026-04-28T06:07:19.722201Z","end":"2026-04-28T06:07:20.293535Z","steps":["trace[1382761061] 'process raft request' (duration: 571.192797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:20.293661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.695404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:07:20.293695Z","caller":"traceutil/trace.go:171","msg":"trace[373854431] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1021; }","duration":"221.780786ms","start":"2026-04-28T06:07:20.071903Z","end":"2026-04-28T06:07:20.293684Z","steps":["trace[373854431] 'agreement among raft nodes before linearized reading' (duration: 221.705064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:20.293732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:19.722174Z","time spent":"571.483073ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:07:20.938914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.977609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:07:20.938996Z","caller":"traceutil/trace.go:171","msg":"trace[1354397415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"512.105462ms","start":"2026-04-28T06:07:20.426872Z","end":"2026-04-28T06:07:20.938977Z","steps":["trace[1354397415] 'range keys from in-memory index tree' (duration: 511.865596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:20.939037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:20.426854Z","time spent":"512.174734ms","remote":"127.0.0.1:54758","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-04-28T06:07:21.418372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366372245,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:07:21.919292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366372245,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:07:22.384426Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.874534742s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:07:22.384838Z","caller":"traceutil/trace.go:171","msg":"trace[1132618480] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"1.875076396s","start":"2026-04-28T06:07:20.509737Z","end":"2026-04-28T06:07:22.384813Z","steps":["trace[1132618480] 'process raft request' (duration: 1.874931003s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:22.38486Z","caller":"traceutil/trace.go:171","msg":"trace[1641544580] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1061; }","duration":"1.466521451s","start":"2026-04-28T06:07:20.918301Z","end":"2026-04-28T06:07:22.384822Z","steps":["trace[1641544580] 'read index received' (duration: 1.46650586s)","trace[1641544580] 'applied index is now lower than readState.Index' (duration: 12.981µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:07:22.384986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:20.509714Z","time spent":"1.87520021s","remote":"127.0.0.1:55016","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-04-28T06:07:22.385165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46684734s","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-04-28T06:07:22.385242Z","caller":"traceutil/trace.go:171","msg":"trace[281642815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1022; }","duration":"1.466999464s","start":"2026-04-28T06:07:20.918222Z","end":"2026-04-28T06:07:22.385221Z","steps":["trace[281642815] 'agreement among raft nodes before linearized reading' (duration: 1.466707335s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:22.385298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:20.918192Z","time spent":"1.467095127s","remote":"127.0.0.1:55016","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-04-28T06:07:22.397587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.996315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T06:07:22.397628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108393454s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-28T06:07:22.39767Z","caller":"traceutil/trace.go:171","msg":"trace[137253127] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1024; }","duration":"194.13478ms","start":"2026-04-28T06:07:22.203515Z","end":"2026-04-28T06:07:22.39765Z","steps":["trace[137253127] 'agreement among raft nodes before linearized reading' (duration: 193.956195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:22.397658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1082095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T06:07:22.397688Z","caller":"traceutil/trace.go:171","msg":"trace[372035970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1024; }","duration":"1.108518467s","start":"2026-04-28T06:07:21.289153Z","end":"2026-04-28T06:07:22.397672Z","steps":["trace[372035970] 'agreement among raft nodes before linearized reading' (duration: 1.108256751s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:22.397719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109084123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-28T06:07:22.397731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:21.289131Z","time spent":"1.10859381s","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T06:07:22.39773Z","caller":"traceutil/trace.go:171","msg":"trace[699884729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1024; }","duration":"1.108313212s","start":"2026-04-28T06:07:21.2894Z","end":"2026-04-28T06:07:22.397714Z","steps":["trace[699884729] 'agreement among raft nodes before linearized reading' (duration: 1.108096337s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:22.397757Z","caller":"traceutil/trace.go:171","msg":"trace[2001735191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1024; }","duration":"1.109172235s","start":"2026-04-28T06:07:21.288574Z","end":"2026-04-28T06:07:22.397746Z","steps":["trace[2001735191] 'agreement among raft nodes before linearized reading' (duration: 1.109035012s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:22.397771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:21.289393Z","time spent":"1.108369535s","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:07:22.397781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:21.288538Z","time spent":"1.109237738s","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:07:22.39778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328700521s","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-04-28T06:07:22.397839Z","caller":"traceutil/trace.go:171","msg":"trace[57626294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1024; }","duration":"1.328792043s","start":"2026-04-28T06:07:21.06903Z","end":"2026-04-28T06:07:22.397822Z","steps":["trace[57626294] 'agreement among raft nodes before linearized reading' (duration: 1.328600837s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:22.397897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457793026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:07:22.39796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:21.069011Z","time spent":"1.328936107s","remote":"127.0.0.1:55016","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-04-28T06:07:22.398004Z","caller":"traceutil/trace.go:171","msg":"trace[2015103359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"1.457856728s","start":"2026-04-28T06:07:20.940056Z","end":"2026-04-28T06:07:22.397913Z","steps":["trace[2015103359] 'agreement among raft nodes before linearized reading' (duration: 1.457318783s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:22.398025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:20.940037Z","time spent":"1.45798323s","remote":"127.0.0.1:54744","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-04-28T06:07:22.555077Z","caller":"traceutil/trace.go:171","msg":"trace[2020428301] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1064; }","duration":"148.106986ms","start":"2026-04-28T06:07:22.406954Z","end":"2026-04-28T06:07:22.555061Z","steps":["trace[2020428301] 'read index received' (duration: 42.62877ms)","trace[2020428301] 'applied index is now lower than readState.Index' (duration: 105.477506ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:07:22.555114Z","caller":"traceutil/trace.go:171","msg":"trace[563764556] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"151.651012ms","start":"2026-04-28T06:07:22.403437Z","end":"2026-04-28T06:07:22.555088Z","steps":["trace[563764556] 'process raft request' (duration: 151.57745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:22.555173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.202889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:07:22.555168Z","caller":"traceutil/trace.go:171","msg":"trace[927803440] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"151.492619ms","start":"2026-04-28T06:07:22.403654Z","end":"2026-04-28T06:07:22.555146Z","steps":["trace[927803440] 'process raft request' (duration: 151.381256ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:22.555191Z","caller":"traceutil/trace.go:171","msg":"trace[246336531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1028; }","duration":"148.252861ms","start":"2026-04-28T06:07:22.406933Z","end":"2026-04-28T06:07:22.555186Z","steps":["trace[246336531] 'agreement among raft nodes before linearized reading' (duration: 148.190809ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:22.555497Z","caller":"traceutil/trace.go:171","msg":"trace[1889802269] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"152.056104ms","start":"2026-04-28T06:07:22.403423Z","end":"2026-04-28T06:07:22.555479Z","steps":["trace[1889802269] 'process raft request' (duration: 151.499939ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:23.599238Z","caller":"traceutil/trace.go:171","msg":"trace[973179360] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"130.965005ms","start":"2026-04-28T06:07:23.468253Z","end":"2026-04-28T06:07:23.599218Z","steps":["trace[973179360] 'process raft request' (duration: 130.834791ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:24.537756Z","caller":"traceutil/trace.go:171","msg":"trace[855497] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"113.092512ms","start":"2026-04-28T06:07:24.424626Z","end":"2026-04-28T06:07:24.537718Z","steps":["trace[855497] 'read index received' (duration: 25.723424ms)","trace[855497] 'applied index is now lower than readState.Index' (duration: 87.368008ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:07:24.537888Z","caller":"traceutil/trace.go:171","msg":"trace[76368159] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"132.782413ms","start":"2026-04-28T06:07:24.405093Z","end":"2026-04-28T06:07:24.537875Z","steps":["trace[76368159] 'process raft request' (duration: 45.20815ms)","trace[76368159] 'compare' (duration: 87.299676ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:07:24.538071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.428811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:07:24.538126Z","caller":"traceutil/trace.go:171","msg":"trace[946646068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1031; }","duration":"113.505393ms","start":"2026-04-28T06:07:24.424605Z","end":"2026-04-28T06:07:24.538111Z","steps":["trace[946646068] 'agreement among raft nodes before linearized reading' (duration: 113.434921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:24.776456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.124005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:07:24.776726Z","caller":"traceutil/trace.go:171","msg":"trace[711962337] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"173.495653ms","start":"2026-04-28T06:07:24.603207Z","end":"2026-04-28T06:07:24.776703Z","steps":["trace[711962337] 'process raft request' (duration: 54.042478ms)","trace[711962337] 'compare' (duration: 118.768386ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:07:24.776767Z","caller":"traceutil/trace.go:171","msg":"trace[669149361] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"172.947418ms","start":"2026-04-28T06:07:24.603798Z","end":"2026-04-28T06:07:24.776746Z","steps":["trace[669149361] 'process raft request' (duration: 172.806483ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:34.036338Z","caller":"traceutil/trace.go:171","msg":"trace[1061589172] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"158.809736ms","start":"2026-04-28T06:07:33.87751Z","end":"2026-04-28T06:07:34.03632Z","steps":["trace[1061589172] 'process raft request' (duration: 158.669563ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:34.815542Z","caller":"traceutil/trace.go:171","msg":"trace[838462821] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"322.987357ms","start":"2026-04-28T06:07:34.49254Z","end":"2026-04-28T06:07:34.815528Z","steps":["trace[838462821] 'read index received' (duration: 322.859144ms)","trace[838462821] 'applied index is now lower than readState.Index' (duration: 127.493µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:07:34.815607Z","caller":"traceutil/trace.go:171","msg":"trace[1613236082] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"349.477351ms","start":"2026-04-28T06:07:34.466078Z","end":"2026-04-28T06:07:34.815556Z","steps":["trace[1613236082] 'process raft request' (duration: 349.321937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:34.815675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.128272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:07:34.815706Z","caller":"traceutil/trace.go:171","msg":"trace[1619762852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"323.226814ms","start":"2026-04-28T06:07:34.49247Z","end":"2026-04-28T06:07:34.815697Z","steps":["trace[1619762852] 'agreement among raft nodes before linearized reading' (duration: 323.160322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:34.815735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:34.492452Z","time spent":"323.275906ms","remote":"127.0.0.1:54758","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-04-28T06:07:34.815795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:34.466056Z","time spent":"349.638216ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:07:41.050148Z","caller":"traceutil/trace.go:171","msg":"trace[539967118] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"110.809585ms","start":"2026-04-28T06:07:40.939319Z","end":"2026-04-28T06:07:41.050129Z","steps":["trace[539967118] 'process raft request' (duration: 110.131277ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:41.050174Z","caller":"traceutil/trace.go:171","msg":"trace[602054114] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"110.783265ms","start":"2026-04-28T06:07:40.939374Z","end":"2026-04-28T06:07:41.050157Z","steps":["trace[602054114] 'process raft request' (duration: 110.700223ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:41.050285Z","caller":"traceutil/trace.go:171","msg":"trace[573988185] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"110.565849ms","start":"2026-04-28T06:07:40.93971Z","end":"2026-04-28T06:07:41.050276Z","steps":["trace[573988185] 'process raft request' (duration: 110.413945ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:43.346358Z","caller":"traceutil/trace.go:171","msg":"trace[2115255812] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"302.879615ms","start":"2026-04-28T06:07:43.04344Z","end":"2026-04-28T06:07:43.346319Z","steps":["trace[2115255812] 'process raft request' (duration: 302.646489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:43.346571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.409713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T06:07:43.346604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:43.043413Z","time spent":"303.038329ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:07:43.346658Z","caller":"traceutil/trace.go:171","msg":"trace[814340652] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1091; }","duration":"292.542236ms","start":"2026-04-28T06:07:43.054103Z","end":"2026-04-28T06:07:43.346645Z","steps":["trace[814340652] 'agreement among raft nodes before linearized reading' (duration: 292.28965ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:43.346282Z","caller":"traceutil/trace.go:171","msg":"trace[1859199197] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"292.113975ms","start":"2026-04-28T06:07:43.05414Z","end":"2026-04-28T06:07:43.346254Z","steps":["trace[1859199197] 'read index received' (duration: 291.833267ms)","trace[1859199197] 'applied index is now lower than readState.Index' (duration: 279.448µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:07:43.347108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.708322ms","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-04-28T06:07:43.347149Z","caller":"traceutil/trace.go:171","msg":"trace[794494962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1091; }","duration":"292.784243ms","start":"2026-04-28T06:07:43.054354Z","end":"2026-04-28T06:07:43.347139Z","steps":["trace[794494962] 'agreement among raft nodes before linearized reading' (duration: 292.580167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:43.347377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.697601ms","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-04-28T06:07:43.34745Z","caller":"traceutil/trace.go:171","msg":"trace[826135138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1091; }","duration":"292.786154ms","start":"2026-04-28T06:07:43.054646Z","end":"2026-04-28T06:07:43.347432Z","steps":["trace[826135138] 'agreement among raft nodes before linearized reading' (duration: 292.580418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:45.48713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366372435,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:07:45.988212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366372435,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:07:46.006974Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.153327125s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:07:46.007427Z","caller":"traceutil/trace.go:171","msg":"trace[1986922980] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1139; }","duration":"1.020631732s","start":"2026-04-28T06:07:44.986772Z","end":"2026-04-28T06:07:46.007404Z","steps":["trace[1986922980] 'read index received' (duration: 1.020340114s)","trace[1986922980] 'applied index is now lower than readState.Index' (duration: 290.658µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:07:46.007544Z","caller":"traceutil/trace.go:171","msg":"trace[1272147415] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"1.153943022s","start":"2026-04-28T06:07:44.853543Z","end":"2026-04-28T06:07:46.007486Z","steps":["trace[1272147415] 'process raft request' (duration: 1.153630414s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:46.007674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020894948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-28T06:07:46.007691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:44.853523Z","time spent":"1.154069875s","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:07:46.007708Z","caller":"traceutil/trace.go:171","msg":"trace[166918022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1096; }","duration":"1.02097077s","start":"2026-04-28T06:07:44.986728Z","end":"2026-04-28T06:07:46.007699Z","steps":["trace[166918022] 'agreement among raft nodes before linearized reading' (duration: 1.020808316s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:46.007744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:44.986707Z","time spent":"1.021025262s","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:07:46.007716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.676272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:07:46.007807Z","caller":"traceutil/trace.go:171","msg":"trace[1923334144] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1096; }","duration":"826.810486ms","start":"2026-04-28T06:07:45.180966Z","end":"2026-04-28T06:07:46.007777Z","steps":["trace[1923334144] 'agreement among raft nodes before linearized reading' (duration: 826.657071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:46.007842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.381725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:07:46.007867Z","caller":"traceutil/trace.go:171","msg":"trace[1172299709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1096; }","duration":"581.433236ms","start":"2026-04-28T06:07:45.426425Z","end":"2026-04-28T06:07:46.007859Z","steps":["trace[1172299709] 'agreement among raft nodes before linearized reading' (duration: 581.391275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:46.007896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:45.426408Z","time spent":"581.482938ms","remote":"127.0.0.1:54758","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-04-28T06:07:46.007892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:45.180948Z","time spent":"826.920991ms","remote":"127.0.0.1:46940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:07:46.007917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.080835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:07:46.007962Z","caller":"traceutil/trace.go:171","msg":"trace[2060798978] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1096; }","duration":"467.167728ms","start":"2026-04-28T06:07:45.540776Z","end":"2026-04-28T06:07:46.007944Z","steps":["trace[2060798978] 'agreement among raft nodes before linearized reading' (duration: 467.111146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:46.008002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:45.540748Z","time spent":"467.24428ms","remote":"127.0.0.1:39160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:07:46.008112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.763419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T06:07:46.008142Z","caller":"traceutil/trace.go:171","msg":"trace[1608762656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1096; }","duration":"564.82588ms","start":"2026-04-28T06:07:45.443308Z","end":"2026-04-28T06:07:46.008134Z","steps":["trace[1608762656] 'agreement among raft nodes before linearized reading' (duration: 564.736148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:46.008167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:45.443282Z","time spent":"564.880182ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:07:46.007853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.921277ms","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-04-28T06:07:46.008238Z","caller":"traceutil/trace.go:171","msg":"trace[963510724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1096; }","duration":"564.337757ms","start":"2026-04-28T06:07:45.443893Z","end":"2026-04-28T06:07:46.008231Z","steps":["trace[963510724] 'agreement among raft nodes before linearized reading' (duration: 563.823863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:46.008235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.334773ms","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-04-28T06:07:46.008263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:45.443886Z","time spent":"564.370118ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T06:07:46.008274Z","caller":"traceutil/trace.go:171","msg":"trace[425841658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1096; }","duration":"562.405005ms","start":"2026-04-28T06:07:45.445857Z","end":"2026-04-28T06:07:46.008262Z","steps":["trace[425841658] 'agreement among raft nodes before linearized reading' (duration: 562.315202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:46.008269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.838418ms","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-04-28T06:07:46.008346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:45.445838Z","time spent":"562.499418ms","remote":"127.0.0.1:55016","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-04-28T06:07:46.008367Z","caller":"traceutil/trace.go:171","msg":"trace[1401294586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1096; }","duration":"657.977982ms","start":"2026-04-28T06:07:45.350372Z","end":"2026-04-28T06:07:46.00835Z","steps":["trace[1401294586] 'agreement among raft nodes before linearized reading' (duration: 657.781257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:46.008487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:45.350348Z","time spent":"658.119117ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T06:07:46.131107Z","caller":"traceutil/trace.go:171","msg":"trace[338935157] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"117.809855ms","start":"2026-04-28T06:07:46.013272Z","end":"2026-04-28T06:07:46.131082Z","steps":["trace[338935157] 'process raft request' (duration: 117.680131ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:46.147744Z","caller":"traceutil/trace.go:171","msg":"trace[170007242] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"134.176543ms","start":"2026-04-28T06:07:46.013546Z","end":"2026-04-28T06:07:46.147723Z","steps":["trace[170007242] 'process raft request' (duration: 133.998288ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:46.148024Z","caller":"traceutil/trace.go:171","msg":"trace[1444501546] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"133.311621ms","start":"2026-04-28T06:07:46.014704Z","end":"2026-04-28T06:07:46.148015Z","steps":["trace[1444501546] 'process raft request' (duration: 132.978732ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:46.148109Z","caller":"traceutil/trace.go:171","msg":"trace[769951282] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"132.860909ms","start":"2026-04-28T06:07:46.015223Z","end":"2026-04-28T06:07:46.148084Z","steps":["trace[769951282] 'process raft request' (duration: 132.813128ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:46.148458Z","caller":"traceutil/trace.go:171","msg":"trace[1044877712] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"133.644969ms","start":"2026-04-28T06:07:46.014785Z","end":"2026-04-28T06:07:46.14843Z","steps":["trace[1044877712] 'process raft request' (duration: 133.131856ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:48.271282Z","caller":"traceutil/trace.go:171","msg":"trace[2070858486] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"135.88605ms","start":"2026-04-28T06:07:48.135375Z","end":"2026-04-28T06:07:48.271261Z","steps":["trace[2070858486] 'read index received' (duration: 135.772706ms)","trace[2070858486] 'applied index is now lower than readState.Index' (duration: 112.464µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:07:48.271475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.090735ms","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-04-28T06:07:48.271504Z","caller":"traceutil/trace.go:171","msg":"trace[1858680117] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"136.170898ms","start":"2026-04-28T06:07:48.135325Z","end":"2026-04-28T06:07:48.271496Z","steps":["trace[1858680117] 'agreement among raft nodes before linearized reading' (duration: 136.052015ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:48.271435Z","caller":"traceutil/trace.go:171","msg":"trace[1822791535] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"256.717005ms","start":"2026-04-28T06:07:48.014684Z","end":"2026-04-28T06:07:48.271401Z","steps":["trace[1822791535] 'process raft request' (duration: 256.437327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:48.271717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.850338ms","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-04-28T06:07:48.271742Z","caller":"traceutil/trace.go:171","msg":"trace[1444177829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"116.88932ms","start":"2026-04-28T06:07:48.154846Z","end":"2026-04-28T06:07:48.271736Z","steps":["trace[1444177829] 'agreement among raft nodes before linearized reading' (duration: 116.824858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:48.271836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.708994ms","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-04-28T06:07:48.271881Z","caller":"traceutil/trace.go:171","msg":"trace[2032624819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"116.783846ms","start":"2026-04-28T06:07:48.155087Z","end":"2026-04-28T06:07:48.271871Z","steps":["trace[2032624819] 'agreement among raft nodes before linearized reading' (duration: 116.599441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:48.272011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.385763ms","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-04-28T06:07:48.272074Z","caller":"traceutil/trace.go:171","msg":"trace[449449389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1103; }","duration":"117.604779ms","start":"2026-04-28T06:07:48.154453Z","end":"2026-04-28T06:07:48.272058Z","steps":["trace[449449389] 'agreement among raft nodes before linearized reading' (duration: 117.203098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:48.272377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.538837ms","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-04-28T06:07:48.272421Z","caller":"traceutil/trace.go:171","msg":"trace[112435714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"117.603559ms","start":"2026-04-28T06:07:48.154807Z","end":"2026-04-28T06:07:48.27241Z","steps":["trace[112435714] 'agreement among raft nodes before linearized reading' (duration: 117.462725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:50.796527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366372467,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:07:51.297557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366372467,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:07:51.797929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366372467,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:07:52.285998Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.007547258s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:07:52.287291Z","caller":"traceutil/trace.go:171","msg":"trace[1544815424] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1153; }","duration":"1.991179928s","start":"2026-04-28T06:07:50.29608Z","end":"2026-04-28T06:07:52.28726Z","steps":["trace[1544815424] 'read index received' (duration: 1.990973842s)","trace[1544815424] 'applied index is now lower than readState.Index' (duration: 203.916µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:07:52.287397Z","caller":"traceutil/trace.go:171","msg":"trace[698684963] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"2.009014298s","start":"2026-04-28T06:07:50.27832Z","end":"2026-04-28T06:07:52.287335Z","steps":["trace[698684963] 'process raft request' (duration: 2.008754201s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:52.287652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:50.278297Z","time spent":"2.009207443s","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:07:52.287721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.860648513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:07:52.287796Z","caller":"traceutil/trace.go:171","msg":"trace[1370783247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1109; }","duration":"1.860773286s","start":"2026-04-28T06:07:50.427002Z","end":"2026-04-28T06:07:52.287776Z","steps":["trace[1370783247] 'agreement among raft nodes before linearized reading' (duration: 1.860662633s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:52.287797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.9726246s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-28T06:07:52.287814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.973141975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-28T06:07:52.287853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:50.426985Z","time spent":"1.860855378s","remote":"127.0.0.1:54758","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-04-28T06:07:52.287906Z","caller":"traceutil/trace.go:171","msg":"trace[839210192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1109; }","duration":"1.973268098s","start":"2026-04-28T06:07:50.314618Z","end":"2026-04-28T06:07:52.287886Z","steps":["trace[839210192] 'agreement among raft nodes before linearized reading' (duration: 1.973027442s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:52.287939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416023161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:07:52.287953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:50.314602Z","time spent":"1.9733416s","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:07:52.287966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972788414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T06:07:52.287988Z","caller":"traceutil/trace.go:171","msg":"trace[1830854937] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1109; }","duration":"1.416113034s","start":"2026-04-28T06:07:50.871862Z","end":"2026-04-28T06:07:52.287975Z","steps":["trace[1830854937] 'agreement among raft nodes before linearized reading' (duration: 1.415980971s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:52.288017Z","caller":"traceutil/trace.go:171","msg":"trace[476038392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1109; }","duration":"1.972856097s","start":"2026-04-28T06:07:50.315148Z","end":"2026-04-28T06:07:52.288004Z","steps":["trace[476038392] 'agreement among raft nodes before linearized reading' (duration: 1.972731143s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:52.288028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:50.871841Z","time spent":"1.416178165s","remote":"127.0.0.1:39074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:07:52.288017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972856316s","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-04-28T06:07:52.28807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:50.31514Z","time spent":"1.972915098s","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T06:07:52.287851Z","caller":"traceutil/trace.go:171","msg":"trace[681787605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1109; }","duration":"1.972697132s","start":"2026-04-28T06:07:50.315139Z","end":"2026-04-28T06:07:52.287836Z","steps":["trace[681787605] 'agreement among raft nodes before linearized reading' (duration: 1.972555779s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:52.288151Z","caller":"traceutil/trace.go:171","msg":"trace[1398478605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1109; }","duration":"1.97301753s","start":"2026-04-28T06:07:50.315088Z","end":"2026-04-28T06:07:52.288105Z","steps":["trace[1398478605] 'agreement among raft nodes before linearized reading' (duration: 1.972645971s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:52.288163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:50.315128Z","time spent":"1.97301817s","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:07:52.28823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:50.315072Z","time spent":"1.973124214s","remote":"127.0.0.1:55016","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-04-28T06:07:52.287634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.991536277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-28T06:07:52.288474Z","caller":"traceutil/trace.go:171","msg":"trace[779283284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1109; }","duration":"1.992438382s","start":"2026-04-28T06:07:50.29602Z","end":"2026-04-28T06:07:52.288458Z","steps":["trace[779283284] 'agreement among raft nodes before linearized reading' (duration: 1.991436415s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:52.28853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:50.295983Z","time spent":"1.992532465s","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:07:52.288771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.650829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:07:52.288841Z","caller":"traceutil/trace.go:171","msg":"trace[560812564] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1109; }","duration":"808.755612ms","start":"2026-04-28T06:07:51.480069Z","end":"2026-04-28T06:07:52.288825Z","steps":["trace[560812564] 'agreement among raft nodes before linearized reading' (duration: 807.606051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:07:52.288883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:07:51.480052Z","time spent":"808.821073ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:07:52.408985Z","caller":"traceutil/trace.go:171","msg":"trace[377848282] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"114.783622ms","start":"2026-04-28T06:07:52.294164Z","end":"2026-04-28T06:07:52.408947Z","steps":["trace[377848282] 'process raft request' (duration: 24.772754ms)","trace[377848282] 'compare' (duration: 89.642808ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:07:52.409112Z","caller":"traceutil/trace.go:171","msg":"trace[2142826901] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"114.793023ms","start":"2026-04-28T06:07:52.294301Z","end":"2026-04-28T06:07:52.409094Z","steps":["trace[2142826901] 'process raft request' (duration: 114.582928ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:52.409278Z","caller":"traceutil/trace.go:171","msg":"trace[276693878] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"114.632639ms","start":"2026-04-28T06:07:52.294593Z","end":"2026-04-28T06:07:52.409226Z","steps":["trace[276693878] 'process raft request' (duration: 114.423004ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:52.409478Z","caller":"traceutil/trace.go:171","msg":"trace[2017413973] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"115.033339ms","start":"2026-04-28T06:07:52.294391Z","end":"2026-04-28T06:07:52.409424Z","steps":["trace[2017413973] 'process raft request' (duration: 114.578328ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:52.409534Z","caller":"traceutil/trace.go:171","msg":"trace[1518578607] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"114.813013ms","start":"2026-04-28T06:07:52.294686Z","end":"2026-04-28T06:07:52.409499Z","steps":["trace[1518578607] 'process raft request' (duration: 114.364061ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:07:52.418627Z","caller":"traceutil/trace.go:171","msg":"trace[2057356804] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1154; }","duration":"120.321512ms","start":"2026-04-28T06:07:52.298282Z","end":"2026-04-28T06:07:52.418604Z","steps":["trace[2057356804] 'read index received' (duration: 20.680925ms)","trace[2057356804] 'applied index is now lower than readState.Index' (duration: 99.639137ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:07:52.418748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.432264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:07:52.418789Z","caller":"traceutil/trace.go:171","msg":"trace[827057188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"120.525727ms","start":"2026-04-28T06:07:52.298253Z","end":"2026-04-28T06:07:52.418779Z","steps":["trace[827057188] 'agreement among raft nodes before linearized reading' (duration: 120.429385ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:01.462818Z","caller":"traceutil/trace.go:171","msg":"trace[199118832] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"129.247771ms","start":"2026-04-28T06:08:01.333503Z","end":"2026-04-28T06:08:01.462751Z","steps":["trace[199118832] 'process raft request' (duration: 128.514401ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:02.694524Z","caller":"traceutil/trace.go:171","msg":"trace[380863305] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1243; }","duration":"231.764395ms","start":"2026-04-28T06:08:02.462737Z","end":"2026-04-28T06:08:02.694502Z","steps":["trace[380863305] 'read index received' (duration: 231.548249ms)","trace[380863305] 'applied index is now lower than readState.Index' (duration: 215.126µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:08:02.694555Z","caller":"traceutil/trace.go:171","msg":"trace[839147597] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"236.048239ms","start":"2026-04-28T06:08:02.458475Z","end":"2026-04-28T06:08:02.694523Z","steps":["trace[839147597] 'process raft request' (duration: 235.795493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:02.694769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.539321ms","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-04-28T06:08:02.69481Z","caller":"traceutil/trace.go:171","msg":"trace[580613169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1194; }","duration":"230.602193ms","start":"2026-04-28T06:08:02.464198Z","end":"2026-04-28T06:08:02.6948Z","steps":["trace[580613169] 'agreement among raft nodes before linearized reading' (duration: 230.48384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:02.694804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.052643ms","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-04-28T06:08:02.69487Z","caller":"traceutil/trace.go:171","msg":"trace[1864766668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1194; }","duration":"232.173086ms","start":"2026-04-28T06:08:02.462687Z","end":"2026-04-28T06:08:02.69486Z","steps":["trace[1864766668] 'agreement among raft nodes before linearized reading' (duration: 231.94075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:02.694934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.045729ms","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-04-28T06:08:02.694964Z","caller":"traceutil/trace.go:171","msg":"trace[1923082661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1194; }","duration":"230.08284ms","start":"2026-04-28T06:08:02.464873Z","end":"2026-04-28T06:08:02.694956Z","steps":["trace[1923082661] 'agreement among raft nodes before linearized reading' (duration: 230.020929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:02.694883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.074456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:08:02.694999Z","caller":"traceutil/trace.go:171","msg":"trace[1110768792] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1194; }","duration":"110.2215ms","start":"2026-04-28T06:08:02.584764Z","end":"2026-04-28T06:08:02.694985Z","steps":["trace[1110768792] 'agreement among raft nodes before linearized reading' (duration: 110.097767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:02.695141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.595313ms","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-04-28T06:08:02.695179Z","caller":"traceutil/trace.go:171","msg":"trace[1586973588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1194; }","duration":"230.665095ms","start":"2026-04-28T06:08:02.464504Z","end":"2026-04-28T06:08:02.69517Z","steps":["trace[1586973588] 'agreement among raft nodes before linearized reading' (duration: 230.531481ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:03.772532Z","caller":"traceutil/trace.go:171","msg":"trace[1254501335] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"103.693675ms","start":"2026-04-28T06:08:03.668819Z","end":"2026-04-28T06:08:03.772513Z","steps":["trace[1254501335] 'process raft request' (duration: 103.556452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:04.928091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366372658,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:08:05.428902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366372658,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:08:05.929153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366372658,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:08:06.426204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000189711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-28T06:08:06.426351Z","caller":"traceutil/trace.go:171","msg":"trace[1570916920] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000381256s","start":"2026-04-28T06:08:04.425952Z","end":"2026-04-28T06:08:06.426333Z","steps":["trace[1570916920] 'agreement among raft nodes before linearized reading' (duration: 2.000220952s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:06.426431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:04.425935Z","time spent":"2.000488518s","remote":"127.0.0.1:54758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/28 06:08:06 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-28T06:08:06.430355Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366372658,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:08:06.560249Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.220997331s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:08:06.560595Z","caller":"traceutil/trace.go:171","msg":"trace[1485174310] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"2.221411082s","start":"2026-04-28T06:08:04.339167Z","end":"2026-04-28T06:08:06.560578Z","steps":["trace[1485174310] 'process raft request' (duration: 2.22130287s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:06.560724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:04.339143Z","time spent":"2.221515504s","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:08:08.222777Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.495702372s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:08:08.223022Z","caller":"traceutil/trace.go:171","msg":"trace[822894725] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1249; }","duration":"3.797013708s","start":"2026-04-28T06:08:04.425991Z","end":"2026-04-28T06:08:08.223005Z","steps":["trace[822894725] 'read index received' (duration: 2.134545379s)","trace[822894725] 'applied index is now lower than readState.Index' (duration: 1.662467569s)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:08:08.223079Z","caller":"traceutil/trace.go:171","msg":"trace[1406679972] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"3.511406697s","start":"2026-04-28T06:08:04.711665Z","end":"2026-04-28T06:08:08.223072Z","steps":["trace[1406679972] 'process raft request' (duration: 3.511225553s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:08.223158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:04.711648Z","time spent":"3.511446799s","remote":"127.0.0.1:55016","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-04-28T06:08:08.223333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.526081522s","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-04-28T06:08:08.223595Z","caller":"traceutil/trace.go:171","msg":"trace[1243166644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1201; }","duration":"3.52637121s","start":"2026-04-28T06:08:04.697207Z","end":"2026-04-28T06:08:08.223578Z","steps":["trace[1243166644] 'agreement among raft nodes before linearized reading' (duration: 3.526000331s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:08.223628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.643653292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:08:08.223635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:04.697194Z","time spent":"3.526432161s","remote":"127.0.0.1:55016","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-04-28T06:08:08.223667Z","caller":"traceutil/trace.go:171","msg":"trace[887800492] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1201; }","duration":"2.643725994s","start":"2026-04-28T06:08:05.579927Z","end":"2026-04-28T06:08:08.223653Z","steps":["trace[887800492] 'agreement among raft nodes before linearized reading' (duration: 2.643669252s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:08.223692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:05.579908Z","time spent":"2.643777625s","remote":"127.0.0.1:38990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:08:08.223787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.514647144s","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-04-28T06:08:08.223819Z","caller":"traceutil/trace.go:171","msg":"trace[668045614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1201; }","duration":"3.514701396s","start":"2026-04-28T06:08:04.709107Z","end":"2026-04-28T06:08:08.223809Z","steps":["trace[668045614] 'agreement among raft nodes before linearized reading' (duration: 3.514619384s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:08.223851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:04.709095Z","time spent":"3.514746727s","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:08:08.223866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272206548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:08:08.223888Z","caller":"traceutil/trace.go:171","msg":"trace[1607045991] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:1201; }","duration":"1.272304692s","start":"2026-04-28T06:08:06.951576Z","end":"2026-04-28T06:08:08.223881Z","steps":["trace[1607045991] 'agreement among raft nodes before linearized reading' (duration: 1.272213998s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:08.223905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:06.951553Z","time spent":"1.272347512s","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.140\" "} {"level":"warn","ts":"2026-04-28T06:08:08.224024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.499343049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:08:08.224045Z","caller":"traceutil/trace.go:171","msg":"trace[873075267] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1201; }","duration":"1.499403551s","start":"2026-04-28T06:08:06.724635Z","end":"2026-04-28T06:08:08.224038Z","steps":["trace[873075267] 'agreement among raft nodes before linearized reading' (duration: 1.49936478s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:08.224034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.516465814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T06:08:08.224064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:06.724607Z","time spent":"1.499451462s","remote":"127.0.0.1:39066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:08:08.224061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.521443737s","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-04-28T06:08:08.224092Z","caller":"traceutil/trace.go:171","msg":"trace[697311314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1201; }","duration":"3.516538516s","start":"2026-04-28T06:08:04.707537Z","end":"2026-04-28T06:08:08.224075Z","steps":["trace[697311314] 'agreement among raft nodes before linearized reading' (duration: 3.516396292s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:08.224096Z","caller":"traceutil/trace.go:171","msg":"trace[869147660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1201; }","duration":"3.521485089s","start":"2026-04-28T06:08:04.7026Z","end":"2026-04-28T06:08:08.224085Z","steps":["trace[869147660] 'agreement among raft nodes before linearized reading' (duration: 3.521394057s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:08.224135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:04.707529Z","time spent":"3.516596007s","remote":"127.0.0.1:55016","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-04-28T06:08:08.224162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.797818985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:08:08.224181Z","caller":"traceutil/trace.go:171","msg":"trace[1071970867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"1.797873556s","start":"2026-04-28T06:08:06.426301Z","end":"2026-04-28T06:08:08.224175Z","steps":["trace[1071970867] 'agreement among raft nodes before linearized reading' (duration: 1.797844895s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:08.224197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:06.42628Z","time spent":"1.797913208s","remote":"127.0.0.1:54744","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-04-28T06:08:08.22414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:04.702593Z","time spent":"3.52154075s","remote":"127.0.0.1:55016","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-04-28T06:08:08.22453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.516875314s","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-04-28T06:08:08.224575Z","caller":"traceutil/trace.go:171","msg":"trace[1195034207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1201; }","duration":"3.516967357s","start":"2026-04-28T06:08:04.707594Z","end":"2026-04-28T06:08:08.224561Z","steps":["trace[1195034207] 'agreement among raft nodes before linearized reading' (duration: 3.516843834s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:08.224617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:04.70757Z","time spent":"3.517037829s","remote":"127.0.0.1:55016","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-04-28T06:08:23.514447Z","caller":"traceutil/trace.go:171","msg":"trace[781100976] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"219.410292ms","start":"2026-04-28T06:08:23.295016Z","end":"2026-04-28T06:08:23.514426Z","steps":["trace[781100976] 'process raft request' (duration: 219.222517ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:26.899539Z","caller":"traceutil/trace.go:171","msg":"trace[189269811] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"260.684221ms","start":"2026-04-28T06:08:26.638829Z","end":"2026-04-28T06:08:26.899514Z","steps":["trace[189269811] 'process raft request' (duration: 260.498007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:27.287296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.676735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:08:27.287384Z","caller":"traceutil/trace.go:171","msg":"trace[1554313951] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:1294; }","duration":"335.825089ms","start":"2026-04-28T06:08:26.95154Z","end":"2026-04-28T06:08:27.287365Z","steps":["trace[1554313951] 'range keys from in-memory index tree' (duration: 335.433439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:27.287427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:26.951516Z","time spent":"335.90155ms","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.140\" "} {"level":"warn","ts":"2026-04-28T06:08:27.66669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:27.288598Z","time spent":"378.087175ms","remote":"127.0.0.1:54810","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-28T06:08:28.85657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605445366372873,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:08:28.924126Z","caller":"traceutil/trace.go:171","msg":"trace[978235914] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"595.73642ms","start":"2026-04-28T06:08:28.328359Z","end":"2026-04-28T06:08:28.924095Z","steps":["trace[978235914] 'process raft request' (duration: 595.556856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:28.924972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:28.328342Z","time spent":"595.896844ms","remote":"127.0.0.1:55016","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-04-28T06:08:29.356017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.460347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:08:29.35631Z","caller":"traceutil/trace.go:171","msg":"trace[1602502494] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"450.725927ms","start":"2026-04-28T06:08:28.905571Z","end":"2026-04-28T06:08:29.356296Z","steps":["trace[1602502494] 'process raft request' (duration: 450.569422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:29.3564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:28.90555Z","time spent":"450.817909ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:08:29.356449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001168731s","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-04-28T06:08:29.356492Z","caller":"traceutil/trace.go:171","msg":"trace[647988192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1299; }","duration":"1.001236951s","start":"2026-04-28T06:08:28.355235Z","end":"2026-04-28T06:08:29.356472Z","steps":["trace[647988192] 'agreement among raft nodes before linearized reading' (duration: 1.001100018s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:29.356521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:28.355215Z","time spent":"1.001299053s","remote":"127.0.0.1:55016","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-04-28T06:08:29.356403Z","caller":"traceutil/trace.go:171","msg":"trace[1640554372] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"614.201248ms","start":"2026-04-28T06:08:28.742176Z","end":"2026-04-28T06:08:29.356377Z","steps":["trace[1640554372] 'process raft request' (duration: 465.256627ms)","trace[1640554372] 'compare' (duration: 148.277873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:08:29.356683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:28.742143Z","time spent":"614.482975ms","remote":"127.0.0.1:55016","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-04-28T06:08:29.356262Z","caller":"traceutil/trace.go:171","msg":"trace[1810116997] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1351; }","duration":"1.000934043s","start":"2026-04-28T06:08:28.355285Z","end":"2026-04-28T06:08:29.356219Z","steps":["trace[1810116997] 'read index received' (duration: 568.752275ms)","trace[1810116997] 'applied index is now lower than readState.Index' (duration: 432.180898ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:08:29.756242Z","caller":"traceutil/trace.go:171","msg":"trace[993889931] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"528.102865ms","start":"2026-04-28T06:08:29.228115Z","end":"2026-04-28T06:08:29.756218Z","steps":["trace[993889931] 'process raft request' (duration: 527.982462ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:29.756264Z","caller":"traceutil/trace.go:171","msg":"trace[1317874107] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1355; }","duration":"399.239523ms","start":"2026-04-28T06:08:29.356985Z","end":"2026-04-28T06:08:29.756225Z","steps":["trace[1317874107] 'read index received' (duration: 399.219433ms)","trace[1317874107] 'applied index is now lower than readState.Index' (duration: 17.54µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:08:29.756421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:29.228095Z","time spent":"528.266159ms","remote":"127.0.0.1:54854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:08:29.75647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324453244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:08:29.756515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.430473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:08:29.75655Z","caller":"traceutil/trace.go:171","msg":"trace[724934405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"1.324596048s","start":"2026-04-28T06:08:28.431933Z","end":"2026-04-28T06:08:29.756529Z","steps":["trace[724934405] 'agreement among raft nodes before linearized reading' (duration: 1.324316341s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:29.756566Z","caller":"traceutil/trace.go:171","msg":"trace[632598355] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1300; }","duration":"993.538446ms","start":"2026-04-28T06:08:28.763015Z","end":"2026-04-28T06:08:29.756553Z","steps":["trace[632598355] 'agreement among raft nodes before linearized reading' (duration: 993.417492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:29.756566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.390203168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-28T06:08:29.756604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:28.431917Z","time spent":"1.324674679s","remote":"127.0.0.1:54758","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-04-28T06:08:29.756606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:28.762986Z","time spent":"993.610637ms","remote":"127.0.0.1:54966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:08:29.756617Z","caller":"traceutil/trace.go:171","msg":"trace[1275686341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1300; }","duration":"1.39026597s","start":"2026-04-28T06:08:28.366333Z","end":"2026-04-28T06:08:29.756598Z","steps":["trace[1275686341] 'agreement among raft nodes before linearized reading' (duration: 1.390085765s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:29.756756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.390775144s","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-04-28T06:08:29.7568Z","caller":"traceutil/trace.go:171","msg":"trace[493460645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1300; }","duration":"1.390829376s","start":"2026-04-28T06:08:28.365958Z","end":"2026-04-28T06:08:29.756788Z","steps":["trace[493460645] 'agreement among raft nodes before linearized reading' (duration: 1.390683502s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:29.756856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:28.365954Z","time spent":"1.390891178s","remote":"127.0.0.1:55016","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-04-28T06:08:29.756927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391048702s","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-04-28T06:08:29.756801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:28.366327Z","time spent":"1.390457595s","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T06:08:29.756958Z","caller":"traceutil/trace.go:171","msg":"trace[93082545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1300; }","duration":"1.391104984s","start":"2026-04-28T06:08:28.365843Z","end":"2026-04-28T06:08:29.756948Z","steps":["trace[93082545] 'agreement among raft nodes before linearized reading' (duration: 1.391009501s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:29.756987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:28.365829Z","time spent":"1.391151624s","remote":"127.0.0.1:55016","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-04-28T06:08:29.77792Z","caller":"traceutil/trace.go:171","msg":"trace[1035165149] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"416.871897ms","start":"2026-04-28T06:08:29.361025Z","end":"2026-04-28T06:08:29.777897Z","steps":["trace[1035165149] 'process raft request' (duration: 416.766044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:29.778069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:29.361012Z","time spent":"416.972049ms","remote":"127.0.0.1:55016","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-04-28T06:08:29.778407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.859282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:08:29.778459Z","caller":"traceutil/trace.go:171","msg":"trace[1905395525] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1301; }","duration":"325.938475ms","start":"2026-04-28T06:08:29.452505Z","end":"2026-04-28T06:08:29.778443Z","steps":["trace[1905395525] 'agreement among raft nodes before linearized reading' (duration: 325.854312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:29.778493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:29.452489Z","time spent":"325.996986ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T06:08:29.778576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.510181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-28T06:08:29.778627Z","caller":"traceutil/trace.go:171","msg":"trace[1422115705] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1301; }","duration":"421.557742ms","start":"2026-04-28T06:08:29.357052Z","end":"2026-04-28T06:08:29.77861Z","steps":["trace[1422115705] 'agreement among raft nodes before linearized reading' (duration: 421.401698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:29.778667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:08:29.357007Z","time spent":"421.649685ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-28T06:08:29.909118Z","caller":"traceutil/trace.go:171","msg":"trace[1977909302] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"125.354307ms","start":"2026-04-28T06:08:29.783705Z","end":"2026-04-28T06:08:29.90906Z","steps":["trace[1977909302] 'process raft request' (duration: 125.10563ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:29.909122Z","caller":"traceutil/trace.go:171","msg":"trace[1850131165] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"124.534585ms","start":"2026-04-28T06:08:29.784557Z","end":"2026-04-28T06:08:29.909091Z","steps":["trace[1850131165] 'process raft request' (duration: 124.394002ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:29.909242Z","caller":"traceutil/trace.go:171","msg":"trace[1118571676] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"122.450768ms","start":"2026-04-28T06:08:29.786764Z","end":"2026-04-28T06:08:29.909215Z","steps":["trace[1118571676] 'process raft request' (duration: 122.264514ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:30.09716Z","caller":"traceutil/trace.go:171","msg":"trace[284368496] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"173.641085ms","start":"2026-04-28T06:08:29.923499Z","end":"2026-04-28T06:08:30.09714Z","steps":["trace[284368496] 'process raft request' (duration: 131.207155ms)","trace[284368496] 'compare' (duration: 42.123841ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:08:30.121652Z","caller":"traceutil/trace.go:171","msg":"trace[840220468] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"195.470731ms","start":"2026-04-28T06:08:29.926159Z","end":"2026-04-28T06:08:30.12163Z","steps":["trace[840220468] 'process raft request' (duration: 195.408819ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:30.121661Z","caller":"traceutil/trace.go:171","msg":"trace[1645845405] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"196.251783ms","start":"2026-04-28T06:08:29.925382Z","end":"2026-04-28T06:08:30.121634Z","steps":["trace[1645845405] 'process raft request' (duration: 196.077118ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:33.266151Z","caller":"traceutil/trace.go:171","msg":"trace[1537426896] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"298.775994ms","start":"2026-04-28T06:08:32.967349Z","end":"2026-04-28T06:08:33.266125Z","steps":["trace[1537426896] 'process raft request' (duration: 298.61779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:35.15836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.74373ms","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-04-28T06:08:35.158447Z","caller":"traceutil/trace.go:171","msg":"trace[1481313144] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1324; }","duration":"118.894213ms","start":"2026-04-28T06:08:35.039533Z","end":"2026-04-28T06:08:35.158427Z","steps":["trace[1481313144] 'count revisions from in-memory index tree' (duration: 118.641616ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:43.090151Z","caller":"traceutil/trace.go:171","msg":"trace[241913933] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"177.352554ms","start":"2026-04-28T06:08:42.912773Z","end":"2026-04-28T06:08:43.090126Z","steps":["trace[241913933] 'process raft request' (duration: 177.139287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:43.338296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.27194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:08:43.338375Z","caller":"traceutil/trace.go:171","msg":"trace[774842126] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1384; }","duration":"107.422164ms","start":"2026-04-28T06:08:43.230937Z","end":"2026-04-28T06:08:43.338359Z","steps":["trace[774842126] 'count revisions from in-memory index tree' (duration: 107.157458ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:51.494011Z","caller":"traceutil/trace.go:171","msg":"trace[1521492576] linearizableReadLoop","detail":"{readStateIndex:1769; appliedIndex:1768; }","duration":"162.823685ms","start":"2026-04-28T06:09:51.33116Z","end":"2026-04-28T06:09:51.493984Z","steps":["trace[1521492576] 'read index received' (duration: 162.6303ms)","trace[1521492576] 'applied index is now lower than readState.Index' (duration: 192.285µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:51.494154Z","caller":"traceutil/trace.go:171","msg":"trace[2000946760] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"165.795713ms","start":"2026-04-28T06:09:51.328322Z","end":"2026-04-28T06:09:51.494118Z","steps":["trace[2000946760] 'process raft request' (duration: 165.536037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:51.494217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.047612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:51.494269Z","caller":"traceutil/trace.go:171","msg":"trace[2060017104] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1698; }","duration":"163.158594ms","start":"2026-04-28T06:09:51.331093Z","end":"2026-04-28T06:09:51.494252Z","steps":["trace[2060017104] 'agreement among raft nodes before linearized reading' (duration: 163.03639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:51.610067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.324623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:51.610273Z","caller":"traceutil/trace.go:171","msg":"trace[1981622795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1698; }","duration":"184.548059ms","start":"2026-04-28T06:09:51.425683Z","end":"2026-04-28T06:09:51.610231Z","steps":["trace[1981622795] 'agreement among raft nodes before linearized reading' (duration: 69.107463ms)","trace[1981622795] 'range keys from in-memory index tree' (duration: 115.20697ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:54.082283Z","caller":"traceutil/trace.go:171","msg":"trace[2059240281] transaction","detail":"{read_only:false; response_revision:1709; number_of_response:1; }","duration":"236.375185ms","start":"2026-04-28T06:09:53.845888Z","end":"2026-04-28T06:09:54.082264Z","steps":["trace[2059240281] 'process raft request' (duration: 236.268032ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:54.68016Z","caller":"traceutil/trace.go:171","msg":"trace[2011193248] transaction","detail":"{read_only:false; response_revision:1713; number_of_response:1; }","duration":"164.107129ms","start":"2026-04-28T06:09:54.51603Z","end":"2026-04-28T06:09:54.680137Z","steps":["trace[2011193248] 'process raft request' (duration: 164.063488ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:54.680494Z","caller":"traceutil/trace.go:171","msg":"trace[1498947853] transaction","detail":"{read_only:false; response_revision:1712; number_of_response:1; }","duration":"164.84574ms","start":"2026-04-28T06:09:54.515612Z","end":"2026-04-28T06:09:54.680458Z","steps":["trace[1498947853] 'process raft request' (duration: 164.224413ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:01.069835Z","caller":"traceutil/trace.go:171","msg":"trace[1632449276] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"227.98627ms","start":"2026-04-28T06:11:00.841823Z","end":"2026-04-28T06:11:01.069809Z","steps":["trace[1632449276] 'process raft request' (duration: 186.715716ms)","trace[1632449276] 'compare' (duration: 41.138591ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:11:31.439928Z","caller":"traceutil/trace.go:171","msg":"trace[836682761] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"126.630306ms","start":"2026-04-28T06:11:31.313029Z","end":"2026-04-28T06:11:31.43966Z","steps":["trace[836682761] 'process raft request' (duration: 34.337062ms)","trace[836682761] 'compare' (duration: 91.880874ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:11:31.834213Z","caller":"traceutil/trace.go:171","msg":"trace[1476075040] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2248; }","duration":"407.19461ms","start":"2026-04-28T06:11:31.426986Z","end":"2026-04-28T06:11:31.834181Z","steps":["trace[1476075040] 'read index received' (duration: 357.701109ms)","trace[1476075040] 'applied index is now lower than readState.Index' (duration: 49.492471ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:11:31.834396Z","caller":"traceutil/trace.go:171","msg":"trace[1231042029] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"520.873916ms","start":"2026-04-28T06:11:31.313494Z","end":"2026-04-28T06:11:31.834368Z","steps":["trace[1231042029] 'process raft request' (duration: 471.134029ms)","trace[1231042029] 'compare' (duration: 49.021948ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:11:31.834516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:31.313461Z","time spent":"520.970708ms","remote":"127.0.0.1:55016","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-04-28T06:11:31.834686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.679043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:11:31.83478Z","caller":"traceutil/trace.go:171","msg":"trace[994663986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2158; }","duration":"407.808026ms","start":"2026-04-28T06:11:31.426953Z","end":"2026-04-28T06:11:31.834761Z","steps":["trace[994663986] 'agreement among raft nodes before linearized reading' (duration: 407.647382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:31.834686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.052155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:11:31.834914Z","caller":"traceutil/trace.go:171","msg":"trace[691868256] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2158; }","duration":"179.337833ms","start":"2026-04-28T06:11:31.655544Z","end":"2026-04-28T06:11:31.834882Z","steps":["trace[691868256] 'agreement among raft nodes before linearized reading' (duration: 179.046265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:31.834834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:11:31.42694Z","time spent":"407.885438ms","remote":"127.0.0.1:54758","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-04-28T06:11:32.435336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.821352ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T06:11:32.435689Z","caller":"traceutil/trace.go:171","msg":"trace[1580712690] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2249; }","duration":"244.76297ms","start":"2026-04-28T06:11:32.190898Z","end":"2026-04-28T06:11:32.435661Z","steps":["trace[1580712690] 'read index received' (duration: 50.642821ms)","trace[1580712690] 'applied index is now lower than readState.Index' (duration: 194.118189ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:11:32.435728Z","caller":"traceutil/trace.go:171","msg":"trace[434136368] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"244.099502ms","start":"2026-04-28T06:11:32.191604Z","end":"2026-04-28T06:11:32.435704Z","steps":["trace[434136368] 'process raft request' (duration: 243.860536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:32.435919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.010516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-04-28T06:11:32.435948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.577881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-28T06:11:32.435958Z","caller":"traceutil/trace.go:171","msg":"trace[1138365868] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2159; }","duration":"245.08978ms","start":"2026-04-28T06:11:32.190856Z","end":"2026-04-28T06:11:32.435946Z","steps":["trace[1138365868] 'agreement among raft nodes before linearized reading' (duration: 244.938026ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:32.435981Z","caller":"traceutil/trace.go:171","msg":"trace[1651628309] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2159; }","duration":"229.660454ms","start":"2026-04-28T06:11:32.206312Z","end":"2026-04-28T06:11:32.435973Z","steps":["trace[1651628309] 'agreement among raft nodes before linearized reading' (duration: 229.576111ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:33.702645Z","caller":"traceutil/trace.go:171","msg":"trace[564424843] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"204.843442ms","start":"2026-04-28T06:11:33.497774Z","end":"2026-04-28T06:11:33.702618Z","steps":["trace[564424843] 'process raft request' (duration: 140.597494ms)","trace[564424843] 'compare' (duration: 64.144266ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:11:34.675717Z","caller":"traceutil/trace.go:171","msg":"trace[929436839] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2260; }","duration":"112.902447ms","start":"2026-04-28T06:11:34.562797Z","end":"2026-04-28T06:11:34.6757Z","steps":["trace[929436839] 'read index received' (duration: 112.702281ms)","trace[929436839] 'applied index is now lower than readState.Index' (duration: 199.526µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:11:34.675815Z","caller":"traceutil/trace.go:171","msg":"trace[906521270] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"140.306047ms","start":"2026-04-28T06:11:34.535489Z","end":"2026-04-28T06:11:34.675795Z","steps":["trace[906521270] 'process raft request' (duration: 140.060441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:34.6761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.291887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363694"} {"level":"info","ts":"2026-04-28T06:11:34.676146Z","caller":"traceutil/trace.go:171","msg":"trace[1158572204] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2169; }","duration":"113.367619ms","start":"2026-04-28T06:11:34.562764Z","end":"2026-04-28T06:11:34.676131Z","steps":["trace[1158572204] 'agreement among raft nodes before linearized reading' (duration: 113.120133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:11:34.892088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.262382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:11:34.892341Z","caller":"traceutil/trace.go:171","msg":"trace[1030547151] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"212.267238ms","start":"2026-04-28T06:11:34.680046Z","end":"2026-04-28T06:11:34.892313Z","steps":["trace[1030547151] 'process raft request' (duration: 105.706358ms)","trace[1030547151] 'compare' (duration: 106.15724ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:11:35.09291Z","caller":"traceutil/trace.go:171","msg":"trace[65786754] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2262; }","duration":"104.113426ms","start":"2026-04-28T06:11:34.988774Z","end":"2026-04-28T06:11:35.092887Z","steps":["trace[65786754] 'read index received' (duration: 97.541823ms)","trace[65786754] 'applied index is now lower than readState.Index' (duration: 6.570443ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:11:35.093301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.504796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363694"} {"level":"info","ts":"2026-04-28T06:11:35.093378Z","caller":"traceutil/trace.go:171","msg":"trace[1399155692] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2171; }","duration":"104.616969ms","start":"2026-04-28T06:11:34.988741Z","end":"2026-04-28T06:11:35.093358Z","steps":["trace[1399155692] 'agreement among raft nodes before linearized reading' (duration: 104.229069ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:35.093446Z","caller":"traceutil/trace.go:171","msg":"trace[1421780463] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"161.177716ms","start":"2026-04-28T06:11:34.93224Z","end":"2026-04-28T06:11:35.093418Z","steps":["trace[1421780463] 'process raft request' (duration: 154.001147ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:35.773275Z","caller":"traceutil/trace.go:171","msg":"trace[12161348] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"152.411354ms","start":"2026-04-28T06:11:35.620831Z","end":"2026-04-28T06:11:35.773243Z","steps":["trace[12161348] 'process raft request' (duration: 152.105146ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:35.926939Z","caller":"traceutil/trace.go:171","msg":"trace[55333616] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"149.025756ms","start":"2026-04-28T06:11:35.777874Z","end":"2026-04-28T06:11:35.9269Z","steps":["trace[55333616] 'process raft request' (duration: 148.768689ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:11:46.665989Z","caller":"traceutil/trace.go:171","msg":"trace[1654175722] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"177.33754ms","start":"2026-04-28T06:11:46.488622Z","end":"2026-04-28T06:11:46.66596Z","steps":["trace[1654175722] 'process raft request' (duration: 177.106934ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:12:05.181352Z","caller":"traceutil/trace.go:171","msg":"trace[34330891] linearizableReadLoop","detail":"{readStateIndex:2443; appliedIndex:2442; }","duration":"132.845304ms","start":"2026-04-28T06:12:05.048494Z","end":"2026-04-28T06:12:05.181339Z","steps":["trace[34330891] 'read index received' (duration: 132.720371ms)","trace[34330891] 'applied index is now lower than readState.Index' (duration: 124.443µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:12:05.181463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.953918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:841"} {"level":"info","ts":"2026-04-28T06:12:05.18145Z","caller":"traceutil/trace.go:171","msg":"trace[176281211] transaction","detail":"{read_only:false; response_revision:2343; number_of_response:1; }","duration":"157.432727ms","start":"2026-04-28T06:12:05.023986Z","end":"2026-04-28T06:12:05.181419Z","steps":["trace[176281211] 'process raft request' (duration: 157.272293ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:12:05.181487Z","caller":"traceutil/trace.go:171","msg":"trace[1833483135] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:2343; }","duration":"132.988569ms","start":"2026-04-28T06:12:05.04849Z","end":"2026-04-28T06:12:05.181479Z","steps":["trace[1833483135] 'agreement among raft nodes before linearized reading' (duration: 132.920436ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:12:06.316558Z","caller":"traceutil/trace.go:171","msg":"trace[532026198] transaction","detail":"{read_only:false; response_revision:2349; number_of_response:1; }","duration":"171.146118ms","start":"2026-04-28T06:12:06.145383Z","end":"2026-04-28T06:12:06.31653Z","steps":["trace[532026198] 'process raft request' (duration: 170.755527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:12:06.316744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.901889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:663"} {"level":"info","ts":"2026-04-28T06:12:06.316785Z","caller":"traceutil/trace.go:171","msg":"trace[120929088] linearizableReadLoop","detail":"{readStateIndex:2449; appliedIndex:2448; }","duration":"142.53233ms","start":"2026-04-28T06:12:06.173823Z","end":"2026-04-28T06:12:06.316355Z","steps":["trace[120929088] 'read index received' (duration: 142.318343ms)","trace[120929088] 'applied index is now lower than readState.Index' (duration: 213.227µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:12:06.316822Z","caller":"traceutil/trace.go:171","msg":"trace[1574847691] range","detail":"{range_begin:/registry/clusterroles/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:2349; }","duration":"143.011531ms","start":"2026-04-28T06:12:06.173792Z","end":"2026-04-28T06:12:06.316804Z","steps":["trace[1574847691] 'agreement among raft nodes before linearized reading' (duration: 142.864597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:12:06.317266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.169027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:949"} {"level":"info","ts":"2026-04-28T06:12:06.317341Z","caller":"traceutil/trace.go:171","msg":"trace[1446101170] range","detail":"{range_begin:/registry/clusterrolebindings/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:2349; }","duration":"140.26667ms","start":"2026-04-28T06:12:06.177063Z","end":"2026-04-28T06:12:06.31733Z","steps":["trace[1446101170] 'agreement among raft nodes before linearized reading' (duration: 139.812588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:12:29.107845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.880969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:12:29.108092Z","caller":"traceutil/trace.go:171","msg":"trace[469422746] transaction","detail":"{read_only:false; response_revision:2457; number_of_response:1; }","duration":"211.460702ms","start":"2026-04-28T06:12:28.89661Z","end":"2026-04-28T06:12:29.108071Z","steps":["trace[469422746] 'process raft request' (duration: 84.278124ms)","trace[469422746] 'compare' (duration: 126.723226ms)"],"step_count":2}