{"level":"warn","ts":"2026-06-24T00:53:46.250196Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-24T00:53:46.250296Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-06-24T00:53:46.250372Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-24T00:53:46.25038Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-06-24T00:53:46.250396Z","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-06-24T00:53:46.251024Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-06-24T00:53:46.251094Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-06-24T00:53:46.253297Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.995469ms"} {"level":"info","ts":"2026-06-24T00:53:46.2583Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-06-24T00:53:46.258568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-06-24T00:53:46.258583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-06-24T00:53:46.258594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-24T00:53:46.258602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-06-24T00:53:46.258641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-06-24T00:53:46.263107Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-24T00:53:46.264289Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-24T00:53:46.265375Z","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-06-24T00:53:46.267441Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-24T00:53:46.268905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-06-24T00:53:46.269078Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-06-24T00:53:46.268517Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-24T00:53:46.269209Z","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-06-24T00:53:46.269293Z","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-06-24T00:53:46.269545Z","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-06-24T00:53:46.27402Z","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-06-24T00:53:46.2742Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-06-24T00:53:46.274224Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-06-24T00:53:46.274596Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-24T00:53:46.274642Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-24T00:53:46.460318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-06-24T00:53:46.460381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-24T00:53:46.460411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-06-24T00:53:46.460423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-06-24T00:53:46.460429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-06-24T00:53:46.460437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-06-24T00:53:46.460449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-06-24T00:53:46.480487Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T00:53:46.500485Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-06-24T00:53:46.500674Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T00:53:46.500951Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-24T00:53:46.500998Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-24T00:53:46.500905Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T00:53:46.501247Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T00:53:46.501352Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T00:53:46.501572Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T00:53:46.519907Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-24T00:53:46.537586Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"warn","ts":"2026-06-24T00:53:51.393504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.849253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:53:51.393724Z","caller":"traceutil/trace.go:171","msg":"trace[163276446] linearizableReadLoop","detail":"{readStateIndex:234; appliedIndex:233; }","duration":"612.592913ms","start":"2026-06-24T00:53:50.781103Z","end":"2026-06-24T00:53:51.393696Z","steps":["trace[163276446] 'read index received' (duration: 159.023678ms)","trace[163276446] 'applied index is now lower than readState.Index' (duration: 453.567505ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:53:51.393797Z","caller":"traceutil/trace.go:171","msg":"trace[828024860] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"613.055675ms","start":"2026-06-24T00:53:50.780688Z","end":"2026-06-24T00:53:51.393744Z","steps":["trace[828024860] 'process raft request' (duration: 159.46671ms)","trace[828024860] 'compare' (duration: 452.701359ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:53:51.393863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.745757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:53:51.393913Z","caller":"traceutil/trace.go:171","msg":"trace[1507349599] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:228; }","duration":"612.829189ms","start":"2026-06-24T00:53:50.781073Z","end":"2026-06-24T00:53:51.393902Z","steps":["trace[1507349599] 'agreement among raft nodes before linearized reading' (duration: 612.723446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:53:51.393953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:53:50.781058Z","time spent":"612.885351ms","remote":"127.0.0.1:37946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-06-24T00:53:51.394123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:53:50.780672Z","time spent":"613.244962ms","remote":"127.0.0.1:37838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:53:51.773759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.481523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:53:51.773912Z","caller":"traceutil/trace.go:171","msg":"trace[1774994353] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"375.676058ms","start":"2026-06-24T00:53:51.39822Z","end":"2026-06-24T00:53:51.773896Z","steps":["trace[1774994353] 'process raft request' (duration: 375.623487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:53:51.774005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:53:51.398209Z","time spent":"375.77066ms","remote":"127.0.0.1:37838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T00:53:51.773999Z","caller":"traceutil/trace.go:171","msg":"trace[153449073] linearizableReadLoop","detail":"{readStateIndex:235; appliedIndex:234; }","duration":"376.710548ms","start":"2026-06-24T00:53:51.397266Z","end":"2026-06-24T00:53:51.773977Z","steps":["trace[153449073] 'read index received' (duration: 217.815953ms)","trace[153449073] 'applied index is now lower than readState.Index' (duration: 158.892145ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:53:51.774063Z","caller":"traceutil/trace.go:171","msg":"trace[1045193140] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"988.803517ms","start":"2026-06-24T00:53:50.785155Z","end":"2026-06-24T00:53:51.773959Z","steps":["trace[1045193140] 'process raft request' (duration: 829.912472ms)","trace[1045193140] 'compare' (duration: 158.368479ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:53:51.774179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.910744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-24T00:53:51.774247Z","caller":"traceutil/trace.go:171","msg":"trace[417719459] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:230; }","duration":"376.980655ms","start":"2026-06-24T00:53:51.397254Z","end":"2026-06-24T00:53:51.774235Z","steps":["trace[417719459] 'agreement among raft nodes before linearized reading' (duration: 376.775849ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:53:51.774289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:53:51.397204Z","time spent":"377.074919ms","remote":"127.0.0.1:37866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-24T00:53:51.774384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:53:50.785144Z","time spent":"989.055274ms","remote":"127.0.0.1:37928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:53:52.081953Z","caller":"traceutil/trace.go:171","msg":"trace[1895647846] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"304.174748ms","start":"2026-06-24T00:53:51.777742Z","end":"2026-06-24T00:53:52.081917Z","steps":["trace[1895647846] 'process raft request' (duration: 205.757939ms)","trace[1895647846] 'compare' (duration: 98.279616ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:53:52.082273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:53:51.777723Z","time spent":"304.410776ms","remote":"127.0.0.1:37946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":190,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:53:52.389843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.177225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:53:52.390027Z","caller":"traceutil/trace.go:171","msg":"trace[703977095] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"611.915462ms","start":"2026-06-24T00:53:51.778084Z","end":"2026-06-24T00:53:52.39Z","steps":["trace[703977095] 'process raft request' (duration: 442.488189ms)","trace[703977095] 'compare' (duration: 168.886787ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:53:52.390137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:53:51.778065Z","time spent":"612.023836ms","remote":"127.0.0.1:37838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T00:53:52.389962Z","caller":"traceutil/trace.go:171","msg":"trace[1137855814] linearizableReadLoop","detail":"{readStateIndex:238; appliedIndex:236; }","duration":"611.595473ms","start":"2026-06-24T00:53:51.77835Z","end":"2026-06-24T00:53:52.389946Z","steps":["trace[1137855814] 'read index received' (duration: 205.159472ms)","trace[1137855814] 'applied index is now lower than readState.Index' (duration: 406.432931ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:53:52.39035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.986415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-yiol4f\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:53:52.390409Z","caller":"traceutil/trace.go:171","msg":"trace[2102999165] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-yiol4f; range_end:; response_count:0; response_revision:232; }","duration":"612.072967ms","start":"2026-06-24T00:53:51.778322Z","end":"2026-06-24T00:53:52.390395Z","steps":["trace[2102999165] 'agreement among raft nodes before linearized reading' (duration: 611.953033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:53:52.390447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:53:51.778313Z","time spent":"612.126819ms","remote":"127.0.0.1:37852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-yiol4f\" "} {"level":"warn","ts":"2026-06-24T00:53:52.390657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.216229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-06-24T00:53:52.390703Z","caller":"traceutil/trace.go:171","msg":"trace[1710795534] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:232; }","duration":"305.292972ms","start":"2026-06-24T00:53:52.085399Z","end":"2026-06-24T00:53:52.390691Z","steps":["trace[1710795534] 'agreement among raft nodes before linearized reading' (duration: 305.194089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:53:52.39078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:53:52.085369Z","time spent":"305.400285ms","remote":"127.0.0.1:37946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":228,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-06-24T00:53:52.390788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.038782ms","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-06-24T00:53:52.391124Z","caller":"traceutil/trace.go:171","msg":"trace[840330356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:232; }","duration":"107.394192ms","start":"2026-06-24T00:53:52.283708Z","end":"2026-06-24T00:53:52.391102Z","steps":["trace[840330356] 'agreement among raft nodes before linearized reading' (duration: 107.055813ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:23.793738Z","caller":"traceutil/trace.go:171","msg":"trace[1517685956] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"106.657962ms","start":"2026-06-24T00:54:23.687049Z","end":"2026-06-24T00:54:23.793707Z","steps":["trace[1517685956] 'process raft request' (duration: 106.175679ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:25.668328Z","caller":"traceutil/trace.go:171","msg":"trace[1780976160] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"177.532569ms","start":"2026-06-24T00:54:25.490775Z","end":"2026-06-24T00:54:25.668308Z","steps":["trace[1780976160] 'process raft request' (duration: 177.397336ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:25.863311Z","caller":"traceutil/trace.go:171","msg":"trace[1419383217] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:549; }","duration":"214.759304ms","start":"2026-06-24T00:54:25.648513Z","end":"2026-06-24T00:54:25.863272Z","steps":["trace[1419383217] 'read index received' (duration: 19.614794ms)","trace[1419383217] 'applied index is now lower than readState.Index' (duration: 195.14385ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:54:25.863619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.092234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:54:25.863672Z","caller":"traceutil/trace.go:171","msg":"trace[1972559231] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"331.915729ms","start":"2026-06-24T00:54:25.531705Z","end":"2026-06-24T00:54:25.863621Z","steps":["trace[1972559231] 'process raft request' (duration: 331.281871ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:25.863715Z","caller":"traceutil/trace.go:171","msg":"trace[178018187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"215.232827ms","start":"2026-06-24T00:54:25.648472Z","end":"2026-06-24T00:54:25.863705Z","steps":["trace[178018187] 'agreement among raft nodes before linearized reading' (duration: 215.083063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:25.863803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:25.531678Z","time spent":"332.065314ms","remote":"127.0.0.1:37818","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-06-24T00:54:25.863854Z","caller":"traceutil/trace.go:171","msg":"trace[1039042055] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"308.378904ms","start":"2026-06-24T00:54:25.555434Z","end":"2026-06-24T00:54:25.863813Z","steps":["trace[1039042055] 'process raft request' (duration: 307.729546ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:25.863932Z","caller":"traceutil/trace.go:171","msg":"trace[1362134720] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"329.278305ms","start":"2026-06-24T00:54:25.534631Z","end":"2026-06-24T00:54:25.863909Z","steps":["trace[1362134720] 'process raft request' (duration: 328.413701ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:25.863953Z","caller":"traceutil/trace.go:171","msg":"trace[1967160397] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"332.156115ms","start":"2026-06-24T00:54:25.531744Z","end":"2026-06-24T00:54:25.8639Z","steps":["trace[1967160397] 'process raft request' (duration: 331.2755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:25.864003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:25.534613Z","time spent":"329.358397ms","remote":"127.0.0.1:37818","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-06-24T00:54:25.864046Z","caller":"traceutil/trace.go:171","msg":"trace[765136243] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"322.29823ms","start":"2026-06-24T00:54:25.54174Z","end":"2026-06-24T00:54:25.864038Z","steps":["trace[765136243] 'process raft request' (duration: 321.384535ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:25.864036Z","caller":"traceutil/trace.go:171","msg":"trace[1713158181] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"358.129407ms","start":"2026-06-24T00:54:25.505845Z","end":"2026-06-24T00:54:25.863974Z","steps":["trace[1713158181] 'process raft request' (duration: 357.006925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:25.864099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:25.541732Z","time spent":"322.347242ms","remote":"127.0.0.1:37818","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-06-24T00:54:25.863876Z","caller":"traceutil/trace.go:171","msg":"trace[1270018481] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"323.514645ms","start":"2026-06-24T00:54:25.540133Z","end":"2026-06-24T00:54:25.863648Z","steps":["trace[1270018481] 'process raft request' (duration: 322.940748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:25.864121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:25.531739Z","time spent":"332.307669ms","remote":"127.0.0.1:37818","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-06-24T00:54:25.864269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:25.505833Z","time spent":"358.301131ms","remote":"127.0.0.1:37818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:54:25.864284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:25.540122Z","time spent":"324.03994ms","remote":"127.0.0.1:37818","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-06-24T00:54:25.86428Z","caller":"traceutil/trace.go:171","msg":"trace[322080579] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"213.48305ms","start":"2026-06-24T00:54:25.650786Z","end":"2026-06-24T00:54:25.864269Z","steps":["trace[322080579] 'process raft request' (duration: 212.43934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:25.863999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:25.555408Z","time spent":"308.515428ms","remote":"127.0.0.1:37818","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-06-24T00:54:25.864408Z","caller":"traceutil/trace.go:171","msg":"trace[249092262] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"358.555919ms","start":"2026-06-24T00:54:25.505844Z","end":"2026-06-24T00:54:25.8644Z","steps":["trace[249092262] 'process raft request' (duration: 356.876922ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:25.864414Z","caller":"traceutil/trace.go:171","msg":"trace[1811541512] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"332.733642ms","start":"2026-06-24T00:54:25.531669Z","end":"2026-06-24T00:54:25.864403Z","steps":["trace[1811541512] 'process raft request' (duration: 331.283051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:25.864452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:25.505828Z","time spent":"358.60779ms","remote":"127.0.0.1:37818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:54:25.864462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:25.531654Z","time spent":"332.785583ms","remote":"127.0.0.1:37818","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-06-24T00:54:25.864419Z","caller":"traceutil/trace.go:171","msg":"trace[1598345877] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"339.800127ms","start":"2026-06-24T00:54:25.5246Z","end":"2026-06-24T00:54:25.8644Z","steps":["trace[1598345877] 'process raft request' (duration: 338.282095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:25.864614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:25.524584Z","time spent":"339.999524ms","remote":"127.0.0.1:37818","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-06-24T00:54:25.867938Z","caller":"traceutil/trace.go:171","msg":"trace[757726097] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"193.363091ms","start":"2026-06-24T00:54:25.67456Z","end":"2026-06-24T00:54:25.867923Z","steps":["trace[757726097] 'process raft request' (duration: 192.918298ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:25.868204Z","caller":"traceutil/trace.go:171","msg":"trace[583980580] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"191.820597ms","start":"2026-06-24T00:54:25.676372Z","end":"2026-06-24T00:54:25.868193Z","steps":["trace[583980580] 'process raft request' (duration: 191.190039ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:25.868456Z","caller":"traceutil/trace.go:171","msg":"trace[573170224] transaction","detail":"{read_only:false; number_of_response:1; response_revision:544; }","duration":"191.344554ms","start":"2026-06-24T00:54:25.677101Z","end":"2026-06-24T00:54:25.868445Z","steps":["trace[573170224] 'process raft request' (duration: 190.522522ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:28.895139Z","caller":"traceutil/trace.go:171","msg":"trace[1132939567] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"294.368485ms","start":"2026-06-24T00:54:28.600745Z","end":"2026-06-24T00:54:28.895113Z","steps":["trace[1132939567] 'process raft request' (duration: 294.226312ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:28.898967Z","caller":"traceutil/trace.go:171","msg":"trace[1061073621] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"295.443265ms","start":"2026-06-24T00:54:28.603498Z","end":"2026-06-24T00:54:28.898941Z","steps":["trace[1061073621] 'read index received' (duration: 291.486035ms)","trace[1061073621] 'applied index is now lower than readState.Index' (duration: 3.95611ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:54:28.899128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.631332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T00:54:28.899142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.62414ms","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-06-24T00:54:28.899163Z","caller":"traceutil/trace.go:171","msg":"trace[51219597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"250.711853ms","start":"2026-06-24T00:54:28.64844Z","end":"2026-06-24T00:54:28.899152Z","steps":["trace[51219597] 'agreement among raft nodes before linearized reading' (duration: 250.63679ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:28.899202Z","caller":"traceutil/trace.go:171","msg":"trace[349466066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"295.719814ms","start":"2026-06-24T00:54:28.603467Z","end":"2026-06-24T00:54:28.899187Z","steps":["trace[349466066] 'agreement among raft nodes before linearized reading' (duration: 295.59541ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:28.899152Z","caller":"traceutil/trace.go:171","msg":"trace[1085645643] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"296.894996ms","start":"2026-06-24T00:54:28.602142Z","end":"2026-06-24T00:54:28.899037Z","steps":["trace[1085645643] 'process raft request' (duration: 296.66637ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:33.311783Z","caller":"traceutil/trace.go:171","msg":"trace[234505235] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"390.594088ms","start":"2026-06-24T00:54:32.921147Z","end":"2026-06-24T00:54:33.311742Z","steps":["trace[234505235] 'process raft request' (duration: 390.035633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:33.311962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:32.921133Z","time spent":"390.747453ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:54:33.31198Z","caller":"traceutil/trace.go:171","msg":"trace[637130702] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"305.478984ms","start":"2026-06-24T00:54:33.006479Z","end":"2026-06-24T00:54:33.311958Z","steps":["trace[637130702] 'read index received' (duration: 304.647381ms)","trace[637130702] 'applied index is now lower than readState.Index' (duration: 830.603µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:54:33.312128Z","caller":"traceutil/trace.go:171","msg":"trace[1684870620] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"376.043304ms","start":"2026-06-24T00:54:32.93603Z","end":"2026-06-24T00:54:33.312073Z","steps":["trace[1684870620] 'process raft request' (duration: 375.502539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:33.312427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.026934ms","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-06-24T00:54:33.31248Z","caller":"traceutil/trace.go:171","msg":"trace[1222534138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"290.114117ms","start":"2026-06-24T00:54:33.022353Z","end":"2026-06-24T00:54:33.312467Z","steps":["trace[1222534138] 'agreement among raft nodes before linearized reading' (duration: 289.958563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:33.312635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.171763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-67jbj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-24T00:54:33.312669Z","caller":"traceutil/trace.go:171","msg":"trace[2052803845] range","detail":"{range_begin:/registry/pods/kube-system/cilium-67jbj; range_end:; response_count:1; response_revision:642; }","duration":"306.249606ms","start":"2026-06-24T00:54:33.006411Z","end":"2026-06-24T00:54:33.312661Z","steps":["trace[2052803845] 'agreement among raft nodes before linearized reading' (duration: 306.190444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:33.312694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:33.006387Z","time spent":"306.301907ms","remote":"127.0.0.1:37932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-67jbj\" "} {"level":"warn","ts":"2026-06-24T00:54:33.31291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:32.936Z","time spent":"376.165137ms","remote":"127.0.0.1:37852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:54:33.666458Z","caller":"traceutil/trace.go:171","msg":"trace[864504022] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"338.311017ms","start":"2026-06-24T00:54:33.328112Z","end":"2026-06-24T00:54:33.666423Z","steps":["trace[864504022] 'process raft request' (duration: 323.830365ms)","trace[864504022] 'compare' (duration: 14.225215ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:54:33.666609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:33.328092Z","time spent":"338.451801ms","remote":"127.0.0.1:37932","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-06-24T00:54:34.141513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.721438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:54:34.141829Z","caller":"traceutil/trace.go:171","msg":"trace[980569049] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"783.327727ms","start":"2026-06-24T00:54:33.358482Z","end":"2026-06-24T00:54:34.14181Z","steps":["trace[980569049] 'process raft request' (duration: 637.225308ms)","trace[980569049] 'compare' (duration: 145.419689ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:54:34.141967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:33.358466Z","time spent":"783.44588ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:54:34.142169Z","caller":"traceutil/trace.go:171","msg":"trace[1747871240] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:668; }","duration":"492.985162ms","start":"2026-06-24T00:54:33.649172Z","end":"2026-06-24T00:54:34.142157Z","steps":["trace[1747871240] 'read index received' (duration: 2.742567ms)","trace[1747871240] 'applied index is now lower than readState.Index' (duration: 490.241755ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:54:34.142266Z","caller":"traceutil/trace.go:171","msg":"trace[1284003284] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"774.560383ms","start":"2026-06-24T00:54:33.367686Z","end":"2026-06-24T00:54:34.142246Z","steps":["trace[1284003284] 'process raft request' (duration: 773.983286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:34.142526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.334372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:54:34.142601Z","caller":"traceutil/trace.go:171","msg":"trace[473239516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"493.461395ms","start":"2026-06-24T00:54:33.649122Z","end":"2026-06-24T00:54:34.142584Z","steps":["trace[473239516] 'agreement among raft nodes before linearized reading' (duration: 493.28387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:34.142645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:33.649103Z","time spent":"493.534416ms","remote":"127.0.0.1:37782","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-06-24T00:54:34.142667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:33.367671Z","time spent":"774.902493ms","remote":"127.0.0.1:37988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:54:35.149975Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496933317121891,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T00:54:35.180324Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.022268993s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-24T00:54:35.633468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.903109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:54:35.634357Z","caller":"traceutil/trace.go:171","msg":"trace[242910440] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.476351803s","start":"2026-06-24T00:54:34.157975Z","end":"2026-06-24T00:54:35.634327Z","steps":["trace[242910440] 'process raft request' (duration: 1.022531508s)","trace[242910440] 'compare' (duration: 452.54511ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:54:35.634488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:34.157955Z","time spent":"1.476477776s","remote":"127.0.0.1:37932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:54:35.82329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.648497ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-24T00:54:35.823586Z","caller":"traceutil/trace.go:171","msg":"trace[14395037] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"1.173914554s","start":"2026-06-24T00:54:34.64964Z","end":"2026-06-24T00:54:35.823555Z","steps":["trace[14395037] 'read index received' (duration: 530.881566ms)","trace[14395037] 'applied index is now lower than readState.Index' (duration: 643.030398ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:54:35.823756Z","caller":"traceutil/trace.go:171","msg":"trace[601653929] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"184.187056ms","start":"2026-06-24T00:54:35.639501Z","end":"2026-06-24T00:54:35.823688Z","steps":["trace[601653929] 'process raft request' (duration: 183.96952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:35.823888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174213402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:54:35.823935Z","caller":"traceutil/trace.go:171","msg":"trace[1802375775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"1.174343706s","start":"2026-06-24T00:54:34.649578Z","end":"2026-06-24T00:54:35.823922Z","steps":["trace[1802375775] 'agreement among raft nodes before linearized reading' (duration: 1.17416506s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:35.824005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:34.649554Z","time spent":"1.174441539s","remote":"127.0.0.1:37770","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-06-24T00:54:35.84812Z","caller":"traceutil/trace.go:171","msg":"trace[120681521] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"208.517412ms","start":"2026-06-24T00:54:35.639575Z","end":"2026-06-24T00:54:35.848092Z","steps":["trace[120681521] 'process raft request' (duration: 208.244335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:35.848373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.013786ms","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-06-24T00:54:35.848429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.587334ms","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-06-24T00:54:35.848467Z","caller":"traceutil/trace.go:171","msg":"trace[781119062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"532.1432ms","start":"2026-06-24T00:54:35.316293Z","end":"2026-06-24T00:54:35.848436Z","steps":["trace[781119062] 'agreement among raft nodes before linearized reading' (duration: 531.849432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:35.848511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:35.316268Z","time spent":"532.234372ms","remote":"127.0.0.1:38006","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-06-24T00:54:35.848479Z","caller":"traceutil/trace.go:171","msg":"trace[498148401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"495.690078ms","start":"2026-06-24T00:54:35.352775Z","end":"2026-06-24T00:54:35.848465Z","steps":["trace[498148401] 'agreement among raft nodes before linearized reading' (duration: 495.362859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:35.848586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:35.35275Z","time spent":"495.80135ms","remote":"127.0.0.1:38006","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-06-24T00:54:35.848804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.117937ms","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-06-24T00:54:35.848785Z","caller":"traceutil/trace.go:171","msg":"trace[682753551] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"207.467013ms","start":"2026-06-24T00:54:35.640896Z","end":"2026-06-24T00:54:35.848363Z","steps":["trace[682753551] 'process raft request' (duration: 207.157384ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:35.848128Z","caller":"traceutil/trace.go:171","msg":"trace[1048423366] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"208.300725ms","start":"2026-06-24T00:54:35.63981Z","end":"2026-06-24T00:54:35.84811Z","steps":["trace[1048423366] 'process raft request' (duration: 208.170552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:35.848616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.185042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-24T00:54:35.849107Z","caller":"traceutil/trace.go:171","msg":"trace[789605637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:651; }","duration":"927.719887ms","start":"2026-06-24T00:54:34.921373Z","end":"2026-06-24T00:54:35.849092Z","steps":["trace[789605637] 'agreement among raft nodes before linearized reading' (duration: 927.030497ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:35.848863Z","caller":"traceutil/trace.go:171","msg":"trace[759022734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"888.22299ms","start":"2026-06-24T00:54:34.960624Z","end":"2026-06-24T00:54:35.848847Z","steps":["trace[759022734] 'agreement among raft nodes before linearized reading' (duration: 888.047265ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:35.849181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:34.921346Z","time spent":"927.82131ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T00:54:35.849214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:34.960602Z","time spent":"888.59016ms","remote":"127.0.0.1:38006","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-06-24T00:54:36.175967Z","caller":"traceutil/trace.go:171","msg":"trace[51178225] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"320.354188ms","start":"2026-06-24T00:54:35.855586Z","end":"2026-06-24T00:54:36.17594Z","steps":["trace[51178225] 'process raft request' (duration: 259.668842ms)","trace[51178225] 'compare' (duration: 60.374547ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:54:36.175965Z","caller":"traceutil/trace.go:171","msg":"trace[98494275] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"320.311537ms","start":"2026-06-24T00:54:35.855627Z","end":"2026-06-24T00:54:36.175939Z","steps":["trace[98494275] 'process raft request' (duration: 320.134031ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:36.176077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:35.855569Z","time spent":"320.454899ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:54:36.176127Z","caller":"traceutil/trace.go:171","msg":"trace[1663542088] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:677; }","duration":"319.081162ms","start":"2026-06-24T00:54:35.857031Z","end":"2026-06-24T00:54:36.176112Z","steps":["trace[1663542088] 'read index received' (duration: 29.922451ms)","trace[1663542088] 'applied index is now lower than readState.Index' (duration: 289.157891ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:54:36.176142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:35.855607Z","time spent":"320.43714ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T00:54:36.176344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.314028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:54:36.176379Z","caller":"traceutil/trace.go:171","msg":"trace[2120394815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"319.34773ms","start":"2026-06-24T00:54:35.857021Z","end":"2026-06-24T00:54:36.176368Z","steps":["trace[2120394815] 'agreement among raft nodes before linearized reading' (duration: 319.242217ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:36.176232Z","caller":"traceutil/trace.go:171","msg":"trace[89289279] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"320.542732ms","start":"2026-06-24T00:54:35.855657Z","end":"2026-06-24T00:54:36.1762Z","steps":["trace[89289279] 'process raft request' (duration: 320.149431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:36.176582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:35.855649Z","time spent":"320.81089ms","remote":"127.0.0.1:38006","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-06-24T00:54:36.176408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:35.856981Z","time spent":"319.421351ms","remote":"127.0.0.1:37770","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-06-24T00:54:38.372494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.074427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-24T00:54:38.372743Z","caller":"traceutil/trace.go:171","msg":"trace[1178728123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"191.346375ms","start":"2026-06-24T00:54:38.181373Z","end":"2026-06-24T00:54:38.37272Z","steps":["trace[1178728123] 'range keys from in-memory index tree' (duration: 190.954163ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:38.372541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.597091ms","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-06-24T00:54:38.372541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.632991ms","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-06-24T00:54:38.373041Z","caller":"traceutil/trace.go:171","msg":"trace[1039784779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"192.126536ms","start":"2026-06-24T00:54:38.180884Z","end":"2026-06-24T00:54:38.37301Z","steps":["trace[1039784779] 'range keys from in-memory index tree' (duration: 191.467578ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:38.373209Z","caller":"traceutil/trace.go:171","msg":"trace[300377181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:676; }","duration":"193.278607ms","start":"2026-06-24T00:54:38.179842Z","end":"2026-06-24T00:54:38.37312Z","steps":["trace[300377181] 'range keys from in-memory index tree' (duration: 192.458455ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:38.373881Z","caller":"traceutil/trace.go:171","msg":"trace[1840403977] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"143.848005ms","start":"2026-06-24T00:54:38.230014Z","end":"2026-06-24T00:54:38.373862Z","steps":["trace[1840403977] 'process raft request' (duration: 143.729642ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:42.297372Z","caller":"traceutil/trace.go:171","msg":"trace[369710718] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"124.493418ms","start":"2026-06-24T00:54:42.17284Z","end":"2026-06-24T00:54:42.297333Z","steps":["trace[369710718] 'process raft request' (duration: 123.901611ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:48.640711Z","caller":"traceutil/trace.go:171","msg":"trace[364593684] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"119.579051ms","start":"2026-06-24T00:54:48.521111Z","end":"2026-06-24T00:54:48.64069Z","steps":["trace[364593684] 'process raft request' (duration: 119.429607ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:48.689186Z","caller":"traceutil/trace.go:171","msg":"trace[1534649824] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"166.924906ms","start":"2026-06-24T00:54:48.522237Z","end":"2026-06-24T00:54:48.689162Z","steps":["trace[1534649824] 'process raft request' (duration: 165.801275ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:48.689296Z","caller":"traceutil/trace.go:171","msg":"trace[227415998] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"165.462555ms","start":"2026-06-24T00:54:48.523814Z","end":"2026-06-24T00:54:48.689277Z","steps":["trace[227415998] 'process raft request' (duration: 165.184567ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:54:52.153024Z","caller":"traceutil/trace.go:171","msg":"trace[1336523420] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"190.722416ms","start":"2026-06-24T00:54:51.962278Z","end":"2026-06-24T00:54:52.153Z","steps":["trace[1336523420] 'process raft request' (duration: 190.47628ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:52.459011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.205188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:54:52.459174Z","caller":"traceutil/trace.go:171","msg":"trace[818466179] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"301.71932ms","start":"2026-06-24T00:54:52.157389Z","end":"2026-06-24T00:54:52.459109Z","steps":["trace[818466179] 'process raft request' (duration: 194.321617ms)","trace[818466179] 'compare' (duration: 107.057754ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:54:52.459289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:52.157374Z","time spent":"301.876795ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:54:52.461869Z","caller":"traceutil/trace.go:171","msg":"trace[108042957] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:838; }","duration":"141.679965ms","start":"2026-06-24T00:54:52.320167Z","end":"2026-06-24T00:54:52.461847Z","steps":["trace[108042957] 'read index received' (duration: 31.557856ms)","trace[108042957] 'applied index is now lower than readState.Index' (duration: 110.121189ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:54:52.462052Z","caller":"traceutil/trace.go:171","msg":"trace[1783320589] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"300.039614ms","start":"2026-06-24T00:54:52.161979Z","end":"2026-06-24T00:54:52.462019Z","steps":["trace[1783320589] 'process raft request' (duration: 299.760356ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:54:52.462064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.88254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T00:54:52.462148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:54:52.161961Z","time spent":"300.141097ms","remote":"127.0.0.1:37932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4022,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:54:52.46217Z","caller":"traceutil/trace.go:171","msg":"trace[680258646] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:809; }","duration":"142.046986ms","start":"2026-06-24T00:54:52.320111Z","end":"2026-06-24T00:54:52.462158Z","steps":["trace[680258646] 'agreement among raft nodes before linearized reading' (duration: 141.880012ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:55:20.981519Z","caller":"traceutil/trace.go:171","msg":"trace[211327552] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"365.936573ms","start":"2026-06-24T00:55:20.61556Z","end":"2026-06-24T00:55:20.981496Z","steps":["trace[211327552] 'read index received' (duration: 365.755258ms)","trace[211327552] 'applied index is now lower than readState.Index' (duration: 180.385µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:55:20.981803Z","caller":"traceutil/trace.go:171","msg":"trace[697552862] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"441.236564ms","start":"2026-06-24T00:55:20.540551Z","end":"2026-06-24T00:55:20.981787Z","steps":["trace[697552862] 'process raft request' (duration: 440.816462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:55:20.9819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:55:20.540523Z","time spent":"441.324197ms","remote":"127.0.0.1:38006","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-06-24T00:55:20.981713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.134789ms","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-06-24T00:55:20.982382Z","caller":"traceutil/trace.go:171","msg":"trace[1489429809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:999; }","duration":"366.817707ms","start":"2026-06-24T00:55:20.615526Z","end":"2026-06-24T00:55:20.982343Z","steps":["trace[1489429809] 'agreement among raft nodes before linearized reading' (duration: 366.069347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:55:20.9825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:55:20.61551Z","time spent":"366.958251ms","remote":"127.0.0.1:38006","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-06-24T00:55:24.409118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.546977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:55:24.409211Z","caller":"traceutil/trace.go:171","msg":"trace[929076979] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"154.704157ms","start":"2026-06-24T00:55:24.254489Z","end":"2026-06-24T00:55:24.409194Z","steps":["trace[929076979] 'process raft request' (duration: 20.997876ms)","trace[929076979] 'compare' (duration: 133.445374ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:55:24.413513Z","caller":"traceutil/trace.go:171","msg":"trace[1380356217] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"108.188318ms","start":"2026-06-24T00:55:24.30531Z","end":"2026-06-24T00:55:24.413498Z","steps":["trace[1380356217] 'read index received' (duration: 108.101385ms)","trace[1380356217] 'applied index is now lower than readState.Index' (duration: 86.133µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:55:24.41366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.349773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-24T00:55:24.413688Z","caller":"traceutil/trace.go:171","msg":"trace[1988428971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"108.409735ms","start":"2026-06-24T00:55:24.305269Z","end":"2026-06-24T00:55:24.413679Z","steps":["trace[1988428971] 'agreement among raft nodes before linearized reading' (duration: 108.284552ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:55:24.413907Z","caller":"traceutil/trace.go:171","msg":"trace[325595326] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"159.160721ms","start":"2026-06-24T00:55:24.254736Z","end":"2026-06-24T00:55:24.413897Z","steps":["trace[325595326] 'process raft request' (duration: 158.691097ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:55:27.302166Z","caller":"traceutil/trace.go:171","msg":"trace[77972250] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"294.497527ms","start":"2026-06-24T00:55:27.007645Z","end":"2026-06-24T00:55:27.302142Z","steps":["trace[77972250] 'process raft request' (duration: 294.322522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:55:27.914948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.562593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:55:27.915056Z","caller":"traceutil/trace.go:171","msg":"trace[1816778519] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"266.129736ms","start":"2026-06-24T00:55:27.648911Z","end":"2026-06-24T00:55:27.915041Z","steps":["trace[1816778519] 'read index received' (duration: 73.232µs)","trace[1816778519] 'applied index is now lower than readState.Index' (duration: 266.055153ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:55:27.915138Z","caller":"traceutil/trace.go:171","msg":"trace[966855004] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"905.503616ms","start":"2026-06-24T00:55:27.009607Z","end":"2026-06-24T00:55:27.915111Z","steps":["trace[966855004] 'process raft request' (duration: 602.681586ms)","trace[966855004] 'compare' (duration: 302.414099ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:55:27.915225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:55:27.009596Z","time spent":"905.581718ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T00:55:27.915459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.548027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:55:27.915505Z","caller":"traceutil/trace.go:171","msg":"trace[1992868921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"266.621939ms","start":"2026-06-24T00:55:27.648872Z","end":"2026-06-24T00:55:27.915494Z","steps":["trace[1992868921] 'agreement among raft nodes before linearized reading' (duration: 266.210627ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:55:36.598607Z","caller":"traceutil/trace.go:171","msg":"trace[613282058] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"114.395962ms","start":"2026-06-24T00:55:36.484171Z","end":"2026-06-24T00:55:36.598567Z","steps":["trace[613282058] 'process raft request' (duration: 88.096678ms)","trace[613282058] 'compare' (duration: 25.995626ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:55:52.738531Z","caller":"traceutil/trace.go:171","msg":"trace[101085951] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"117.851839ms","start":"2026-06-24T00:55:52.620646Z","end":"2026-06-24T00:55:52.738498Z","steps":["trace[101085951] 'process raft request' (duration: 39.127913ms)","trace[101085951] 'compare' (duration: 78.250573ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:55:52.983054Z","caller":"traceutil/trace.go:171","msg":"trace[1955398800] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"239.613666ms","start":"2026-06-24T00:55:52.743411Z","end":"2026-06-24T00:55:52.983024Z","steps":["trace[1955398800] 'process raft request' (duration: 215.233236ms)","trace[1955398800] 'compare' (duration: 24.262667ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:55:54.049559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.904108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:55:54.049632Z","caller":"traceutil/trace.go:171","msg":"trace[1189582080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1108; }","duration":"400.017101ms","start":"2026-06-24T00:55:53.649597Z","end":"2026-06-24T00:55:54.049614Z","steps":["trace[1189582080] 'range keys from in-memory index tree' (duration: 399.825276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:55:54.049687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:55:53.649579Z","time spent":"400.083762ms","remote":"127.0.0.1:37770","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-06-24T00:55:54.920963Z","caller":"traceutil/trace.go:171","msg":"trace[959262992] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"178.441069ms","start":"2026-06-24T00:55:54.742501Z","end":"2026-06-24T00:55:54.920942Z","steps":["trace[959262992] 'read index received' (duration: 178.290234ms)","trace[959262992] 'applied index is now lower than readState.Index' (duration: 150.155µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:55:54.921032Z","caller":"traceutil/trace.go:171","msg":"trace[1142012374] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"315.229846ms","start":"2026-06-24T00:55:54.605772Z","end":"2026-06-24T00:55:54.921002Z","steps":["trace[1142012374] 'process raft request' (duration: 315.066322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:55:54.921168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:55:54.605757Z","time spent":"315.337059ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T00:55:54.921208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.702486ms","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-06-24T00:55:54.921288Z","caller":"traceutil/trace.go:171","msg":"trace[1575516704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1111; }","duration":"178.806ms","start":"2026-06-24T00:55:54.742451Z","end":"2026-06-24T00:55:54.921257Z","steps":["trace[1575516704] 'agreement among raft nodes before linearized reading' (duration: 178.576283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:55:55.442417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.890161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:55:55.442515Z","caller":"traceutil/trace.go:171","msg":"trace[1714279232] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1156; }","duration":"455.610922ms","start":"2026-06-24T00:55:54.986889Z","end":"2026-06-24T00:55:55.4425Z","steps":["trace[1714279232] 'read index received' (duration: 324.580276ms)","trace[1714279232] 'applied index is now lower than readState.Index' (duration: 131.029646ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:55:55.442579Z","caller":"traceutil/trace.go:171","msg":"trace[492389667] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"516.786989ms","start":"2026-06-24T00:55:54.925782Z","end":"2026-06-24T00:55:55.442569Z","steps":["trace[492389667] 'process raft request' (duration: 385.673761ms)","trace[492389667] 'compare' (duration: 130.773348ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:55:55.44263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:55:54.925762Z","time spent":"516.839402ms","remote":"127.0.0.1:38006","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-06-24T00:55:55.442732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.843039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-24T00:55:55.442786Z","caller":"traceutil/trace.go:171","msg":"trace[996540379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1112; }","duration":"455.93833ms","start":"2026-06-24T00:55:54.986831Z","end":"2026-06-24T00:55:55.44277Z","steps":["trace[996540379] 'agreement among raft nodes before linearized reading' (duration: 455.783836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:55:55.442829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:55:54.986811Z","time spent":"456.008694ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-24T00:55:55.704431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.895837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:55:55.704814Z","caller":"traceutil/trace.go:171","msg":"trace[1803723627] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"368.496653ms","start":"2026-06-24T00:55:55.336286Z","end":"2026-06-24T00:55:55.704783Z","steps":["trace[1803723627] 'process raft request' (duration: 258.141073ms)","trace[1803723627] 'compare' (duration: 109.572268ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:55:55.704969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:55:55.336261Z","time spent":"368.628296ms","remote":"127.0.0.1:38006","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-06-24T00:55:56.353896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.335207ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-24T00:55:56.354427Z","caller":"traceutil/trace.go:171","msg":"trace[765417513] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1157; }","duration":"911.856873ms","start":"2026-06-24T00:55:55.442539Z","end":"2026-06-24T00:55:56.354396Z","steps":["trace[765417513] 'read index received' (duration: 152.007491ms)","trace[765417513] 'applied index is now lower than readState.Index' (duration: 759.847252ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:55:56.354457Z","caller":"traceutil/trace.go:171","msg":"trace[1880235123] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"907.416098ms","start":"2026-06-24T00:55:55.447006Z","end":"2026-06-24T00:55:56.354422Z","steps":["trace[1880235123] 'process raft request' (duration: 907.073369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:55:56.354769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:55:55.446984Z","time spent":"907.555593ms","remote":"127.0.0.1:38006","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-06-24T00:55:56.392392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.45301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-24T00:55:56.393144Z","caller":"traceutil/trace.go:171","msg":"trace[1948851614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1114; }","duration":"322.22312ms","start":"2026-06-24T00:55:56.070872Z","end":"2026-06-24T00:55:56.393095Z","steps":["trace[1948851614] 'agreement among raft nodes before linearized reading' (duration: 283.875761ms)","trace[1948851614] 'range keys from in-memory index tree' (duration: 37.508447ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:55:56.393491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:55:56.070855Z","time spent":"322.594472ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-24T00:55:56.392478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371048896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T00:55:56.393811Z","caller":"traceutil/trace.go:171","msg":"trace[1354001998] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1114; }","duration":"1.372447075s","start":"2026-06-24T00:55:55.02132Z","end":"2026-06-24T00:55:56.393767Z","steps":["trace[1354001998] 'agreement among raft nodes before linearized reading' (duration: 1.333267842s)","trace[1354001998] 'count revisions from in-memory index tree' (duration: 37.770804ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:55:56.392553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.856046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T00:55:56.393962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:55:55.021299Z","time spent":"1.372595748s","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-06-24T00:55:56.392688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.928345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T00:55:56.394072Z","caller":"traceutil/trace.go:171","msg":"trace[845724848] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1114; }","duration":"160.421905ms","start":"2026-06-24T00:55:56.233624Z","end":"2026-06-24T00:55:56.394046Z","steps":["trace[845724848] 'agreement among raft nodes before linearized reading' (duration: 121.110208ms)","trace[845724848] 'count revisions from in-memory index tree' (duration: 37.809795ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:55:56.392926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.534803ms","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-06-24T00:55:56.394167Z","caller":"traceutil/trace.go:171","msg":"trace[2033667321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1114; }","duration":"798.772707ms","start":"2026-06-24T00:55:55.595374Z","end":"2026-06-24T00:55:56.394147Z","steps":["trace[2033667321] 'agreement among raft nodes before linearized reading' (duration: 759.328297ms)","trace[2033667321] 'range keys from in-memory index tree' (duration: 38.094613ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:55:56.394194Z","caller":"traceutil/trace.go:171","msg":"trace[1787793562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"744.408802ms","start":"2026-06-24T00:55:55.649566Z","end":"2026-06-24T00:55:56.393974Z","steps":["trace[1787793562] 'agreement among raft nodes before linearized reading' (duration: 705.194167ms)","trace[1787793562] 'range keys from in-memory index tree' (duration: 37.67287ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:55:56.394471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:55:55.649545Z","time spent":"744.849512ms","remote":"127.0.0.1:37770","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-06-24T00:55:56.394588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:55:55.595315Z","time spent":"798.89264ms","remote":"127.0.0.1:38006","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-06-24T00:55:56.499601Z","caller":"traceutil/trace.go:171","msg":"trace[201882956] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"101.090951ms","start":"2026-06-24T00:55:56.398486Z","end":"2026-06-24T00:55:56.499577Z","steps":["trace[201882956] 'process raft request' (duration: 100.949117ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:55:56.548508Z","caller":"traceutil/trace.go:171","msg":"trace[1517873390] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"148.251656ms","start":"2026-06-24T00:55:56.400228Z","end":"2026-06-24T00:55:56.54848Z","steps":["trace[1517873390] 'process raft request' (duration: 147.918907ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:10.576841Z","caller":"traceutil/trace.go:171","msg":"trace[1318802977] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1260; }","duration":"161.051994ms","start":"2026-06-24T00:56:10.415767Z","end":"2026-06-24T00:56:10.576819Z","steps":["trace[1318802977] 'read index received' (duration: 95.365121ms)","trace[1318802977] 'applied index is now lower than readState.Index' (duration: 65.685153ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:56:10.576846Z","caller":"traceutil/trace.go:171","msg":"trace[1465076461] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"303.545489ms","start":"2026-06-24T00:56:10.273277Z","end":"2026-06-24T00:56:10.576822Z","steps":["trace[1465076461] 'process raft request' (duration: 237.916098ms)","trace[1465076461] 'compare' (duration: 65.487737ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:56:10.577001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:10.273248Z","time spent":"303.666253ms","remote":"127.0.0.1:37798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T00:56:10.577101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.317061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-24T00:56:10.577167Z","caller":"traceutil/trace.go:171","msg":"trace[1843923798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1210; }","duration":"161.427864ms","start":"2026-06-24T00:56:10.415724Z","end":"2026-06-24T00:56:10.577152Z","steps":["trace[1843923798] 'agreement among raft nodes before linearized reading' (duration: 161.154977ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:13.119365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496933317123187,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-24T00:56:13.288264Z","caller":"traceutil/trace.go:171","msg":"trace[2124185835] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"671.26644ms","start":"2026-06-24T00:56:12.616925Z","end":"2026-06-24T00:56:13.288192Z","steps":["trace[2124185835] 'process raft request' (duration: 671.093165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:13.28844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:12.616905Z","time spent":"671.445745ms","remote":"127.0.0.1:38006","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-06-24T00:56:13.779942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.449751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:56:13.780041Z","caller":"traceutil/trace.go:171","msg":"trace[1767962393] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1268; }","duration":"1.161468308s","start":"2026-06-24T00:56:12.618556Z","end":"2026-06-24T00:56:13.780024Z","steps":["trace[1767962393] 'read index received' (duration: 669.446769ms)","trace[1767962393] 'applied index is now lower than readState.Index' (duration: 492.020429ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:56:13.780568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157158348s","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-06-24T00:56:13.780568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130460172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:56:13.78062Z","caller":"traceutil/trace.go:171","msg":"trace[1322092795] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1219; }","duration":"1.15723936s","start":"2026-06-24T00:56:12.623368Z","end":"2026-06-24T00:56:13.780608Z","steps":["trace[1322092795] 'agreement among raft nodes before linearized reading' (duration: 1.156911231s)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:13.780632Z","caller":"traceutil/trace.go:171","msg":"trace[1593842358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1219; }","duration":"1.130572716s","start":"2026-06-24T00:56:12.650043Z","end":"2026-06-24T00:56:13.780616Z","steps":["trace[1593842358] 'agreement among raft nodes before linearized reading' (duration: 1.130295189s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:13.780639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162074134s","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-06-24T00:56:13.780566Z","caller":"traceutil/trace.go:171","msg":"trace[388589973] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"1.162380263s","start":"2026-06-24T00:56:12.618164Z","end":"2026-06-24T00:56:13.780544Z","steps":["trace[388589973] 'process raft request' (duration: 869.245965ms)","trace[388589973] 'compare' (duration: 292.306426ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:56:13.78065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.338053ms","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-06-24T00:56:13.780679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.4015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-24T00:56:13.78083Z","caller":"traceutil/trace.go:171","msg":"trace[300327028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1219; }","duration":"787.589036ms","start":"2026-06-24T00:56:12.993223Z","end":"2026-06-24T00:56:13.780812Z","steps":["trace[300327028] 'agreement among raft nodes before linearized reading' (duration: 787.096262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:13.780877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:12.993209Z","time spent":"787.659057ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-24T00:56:13.780697Z","caller":"traceutil/trace.go:171","msg":"trace[955974773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1219; }","duration":"1.162147247s","start":"2026-06-24T00:56:12.618538Z","end":"2026-06-24T00:56:13.780685Z","steps":["trace[955974773] 'agreement among raft nodes before linearized reading' (duration: 1.161991422s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:13.780979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:12.618532Z","time spent":"1.162425924s","remote":"127.0.0.1:38006","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-06-24T00:56:13.780759Z","caller":"traceutil/trace.go:171","msg":"trace[927525196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1219; }","duration":"265.501238ms","start":"2026-06-24T00:56:13.515238Z","end":"2026-06-24T00:56:13.780739Z","steps":["trace[927525196] 'agreement among raft nodes before linearized reading' (duration: 265.070996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:13.780645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:12.623354Z","time spent":"1.157283461s","remote":"127.0.0.1:38006","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-06-24T00:56:13.780696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:12.650023Z","time spent":"1.130651058s","remote":"127.0.0.1:37770","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-06-24T00:56:13.781002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:12.618152Z","time spent":"1.162561818s","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:56:25.720364Z","caller":"traceutil/trace.go:171","msg":"trace[1243712604] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"380.264551ms","start":"2026-06-24T00:56:25.340074Z","end":"2026-06-24T00:56:25.720338Z","steps":["trace[1243712604] 'process raft request' (duration: 380.083906ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:25.720515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:25.340051Z","time spent":"380.391044ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:56:25.751984Z","caller":"traceutil/trace.go:171","msg":"trace[723755657] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1309; }","duration":"318.444476ms","start":"2026-06-24T00:56:25.433507Z","end":"2026-06-24T00:56:25.751952Z","steps":["trace[723755657] 'read index received' (duration: 286.800443ms)","trace[723755657] 'applied index is now lower than readState.Index' (duration: 31.642103ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:56:25.752142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.60529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:56:25.752199Z","caller":"traceutil/trace.go:171","msg":"trace[1848125358] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1257; }","duration":"318.721643ms","start":"2026-06-24T00:56:25.433461Z","end":"2026-06-24T00:56:25.752182Z","steps":["trace[1848125358] 'agreement among raft nodes before linearized reading' (duration: 318.60503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:25.752297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:25.433439Z","time spent":"318.839737ms","remote":"127.0.0.1:35628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-06-24T00:56:30.667619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.960252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:56:30.667712Z","caller":"traceutil/trace.go:171","msg":"trace[694415251] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"707.20344ms","start":"2026-06-24T00:56:29.960492Z","end":"2026-06-24T00:56:30.667696Z","steps":["trace[694415251] 'read index received' (duration: 445.094843ms)","trace[694415251] 'applied index is now lower than readState.Index' (duration: 262.107557ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:56:30.667798Z","caller":"traceutil/trace.go:171","msg":"trace[1665689947] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"710.288325ms","start":"2026-06-24T00:56:29.957498Z","end":"2026-06-24T00:56:30.667786Z","steps":["trace[1665689947] 'process raft request' (duration: 448.081316ms)","trace[1665689947] 'compare' (duration: 261.716115ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:56:30.667854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:29.957477Z","time spent":"710.346267ms","remote":"127.0.0.1:38006","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-06-24T00:56:30.667889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.852734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:56:30.667933Z","caller":"traceutil/trace.go:171","msg":"trace[477228537] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1273; }","duration":"368.948767ms","start":"2026-06-24T00:56:30.298975Z","end":"2026-06-24T00:56:30.667923Z","steps":["trace[477228537] 'agreement among raft nodes before linearized reading' (duration: 368.870374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:30.667965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:30.298953Z","time spent":"369.004957ms","remote":"127.0.0.1:35670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-24T00:56:30.668017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.117071ms","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-06-24T00:56:30.668059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.334877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-24T00:56:30.668132Z","caller":"traceutil/trace.go:171","msg":"trace[369814751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1273; }","duration":"660.239774ms","start":"2026-06-24T00:56:30.007874Z","end":"2026-06-24T00:56:30.668114Z","steps":["trace[369814751] 'agreement among raft nodes before linearized reading' (duration: 659.973076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:30.668018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.326549ms","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-06-24T00:56:30.668185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:30.007842Z","time spent":"660.332215ms","remote":"127.0.0.1:38006","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-06-24T00:56:30.668197Z","caller":"traceutil/trace.go:171","msg":"trace[1574289305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"645.548716ms","start":"2026-06-24T00:56:30.022642Z","end":"2026-06-24T00:56:30.668191Z","steps":["trace[1574289305] 'agreement among raft nodes before linearized reading' (duration: 645.216446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:30.668233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:30.022631Z","time spent":"645.596726ms","remote":"127.0.0.1:38006","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-06-24T00:56:30.668146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.661772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-24T00:56:30.668268Z","caller":"traceutil/trace.go:171","msg":"trace[2002220213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1273; }","duration":"707.809746ms","start":"2026-06-24T00:56:29.960448Z","end":"2026-06-24T00:56:30.668257Z","steps":["trace[2002220213] 'agreement among raft nodes before linearized reading' (duration: 707.635722ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:30.66814Z","caller":"traceutil/trace.go:171","msg":"trace[317950779] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:1273; }","duration":"437.506073ms","start":"2026-06-24T00:56:30.230618Z","end":"2026-06-24T00:56:30.668124Z","steps":["trace[317950779] 'agreement among raft nodes before linearized reading' (duration: 437.235374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:30.668314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:29.96043Z","time spent":"707.873168ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T00:56:30.668319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:30.230594Z","time spent":"437.719069ms","remote":"127.0.0.1:37798","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.210\" "} {"level":"warn","ts":"2026-06-24T00:56:31.29712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.580865ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:56:31.297246Z","caller":"traceutil/trace.go:171","msg":"trace[114311340] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1273; }","duration":"264.718859ms","start":"2026-06-24T00:56:31.032507Z","end":"2026-06-24T00:56:31.297226Z","steps":["trace[114311340] 'range keys from in-memory index tree' (duration: 264.564255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:31.297278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.457015ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-06-24T00:56:31.297372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:30.669271Z","time spent":"628.09645ms","remote":"127.0.0.1:37798","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-24T00:56:31.349749Z","caller":"traceutil/trace.go:171","msg":"trace[1416931650] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"677.569646ms","start":"2026-06-24T00:56:30.672152Z","end":"2026-06-24T00:56:31.349722Z","steps":["trace[1416931650] 'process raft request' (duration: 677.480753ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:31.349877Z","caller":"traceutil/trace.go:171","msg":"trace[1635163538] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1327; }","duration":"531.205726ms","start":"2026-06-24T00:56:30.818655Z","end":"2026-06-24T00:56:31.34986Z","steps":["trace[1635163538] 'read index received' (duration: 361.309544ms)","trace[1635163538] 'applied index is now lower than readState.Index' (duration: 169.895142ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:56:31.349899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:30.672128Z","time spent":"677.69261ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:56:31.350035Z","caller":"traceutil/trace.go:171","msg":"trace[560304403] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"678.583643ms","start":"2026-06-24T00:56:30.671438Z","end":"2026-06-24T00:56:31.350022Z","steps":["trace[560304403] 'process raft request' (duration: 678.021997ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:31.350107Z","caller":"traceutil/trace.go:171","msg":"trace[1720760914] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"675.713134ms","start":"2026-06-24T00:56:30.674381Z","end":"2026-06-24T00:56:31.350094Z","steps":["trace[1720760914] 'process raft request' (duration: 675.302853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:31.350105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:30.67143Z","time spent":"678.640794ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T00:56:31.35018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:30.674367Z","time spent":"675.772335ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T00:56:31.35029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.639848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:56:31.350327Z","caller":"traceutil/trace.go:171","msg":"trace[1173721408] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1276; }","duration":"531.721181ms","start":"2026-06-24T00:56:30.818596Z","end":"2026-06-24T00:56:31.350317Z","steps":["trace[1173721408] 'agreement among raft nodes before linearized reading' (duration: 531.6621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:31.350359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:30.818566Z","time spent":"531.784853ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-24T00:56:31.6157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.585594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:56:31.615833Z","caller":"traceutil/trace.go:171","msg":"trace[1918745855] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"265.891032ms","start":"2026-06-24T00:56:31.349919Z","end":"2026-06-24T00:56:31.61581Z","steps":["trace[1918745855] 'read index received' (duration: 123.085232ms)","trace[1918745855] 'applied index is now lower than readState.Index' (duration: 142.8033ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:56:31.615937Z","caller":"traceutil/trace.go:171","msg":"trace[1963420974] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"317.437895ms","start":"2026-06-24T00:56:31.29848Z","end":"2026-06-24T00:56:31.615918Z","steps":["trace[1963420974] 'process raft request' (duration: 174.528223ms)","trace[1963420974] 'compare' (duration: 142.233572ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:56:31.616027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:31.298455Z","time spent":"317.525716ms","remote":"127.0.0.1:37798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T00:56:31.616193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.709876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T00:56:31.616295Z","caller":"traceutil/trace.go:171","msg":"trace[1769316170] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1277; }","duration":"365.853129ms","start":"2026-06-24T00:56:31.250423Z","end":"2026-06-24T00:56:31.616276Z","steps":["trace[1769316170] 'agreement among raft nodes before linearized reading' (duration: 365.638984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:31.616348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:31.250409Z","time spent":"365.925893ms","remote":"127.0.0.1:37818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-06-24T00:56:32.082438Z","caller":"traceutil/trace.go:171","msg":"trace[576119361] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"191.888053ms","start":"2026-06-24T00:56:31.890534Z","end":"2026-06-24T00:56:32.082422Z","steps":["trace[576119361] 'read index received' (duration: 191.74542ms)","trace[576119361] 'applied index is now lower than readState.Index' (duration: 141.793µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:56:32.082505Z","caller":"traceutil/trace.go:171","msg":"trace[780794703] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"245.537196ms","start":"2026-06-24T00:56:31.836945Z","end":"2026-06-24T00:56:32.082482Z","steps":["trace[780794703] 'process raft request' (duration: 245.35086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:32.082556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.003906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T00:56:32.082579Z","caller":"traceutil/trace.go:171","msg":"trace[1028142329] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1279; }","duration":"192.048598ms","start":"2026-06-24T00:56:31.890525Z","end":"2026-06-24T00:56:32.082574Z","steps":["trace[1028142329] 'agreement among raft nodes before linearized reading' (duration: 191.960536ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:32.116741Z","caller":"traceutil/trace.go:171","msg":"trace[306255780] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"156.606653ms","start":"2026-06-24T00:56:31.96011Z","end":"2026-06-24T00:56:32.116717Z","steps":["trace[306255780] 'process raft request' (duration: 156.47594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:32.750506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.220344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:56:32.750557Z","caller":"traceutil/trace.go:171","msg":"trace[875889359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"101.312485ms","start":"2026-06-24T00:56:32.649233Z","end":"2026-06-24T00:56:32.750546Z","steps":["trace[875889359] 'range keys from in-memory index tree' (duration: 101.172853ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:33.510557Z","caller":"traceutil/trace.go:171","msg":"trace[1854068373] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"156.584083ms","start":"2026-06-24T00:56:33.353952Z","end":"2026-06-24T00:56:33.510536Z","steps":["trace[1854068373] 'read index received' (duration: 156.388187ms)","trace[1854068373] 'applied index is now lower than readState.Index' (duration: 195.156µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:56:33.510657Z","caller":"traceutil/trace.go:171","msg":"trace[1375744192] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"756.891541ms","start":"2026-06-24T00:56:32.753737Z","end":"2026-06-24T00:56:33.510628Z","steps":["trace[1375744192] 'process raft request' (duration: 756.649014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:33.510783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:32.753719Z","time spent":"756.994503ms","remote":"127.0.0.1:38006","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-06-24T00:56:33.510797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.822319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-24T00:56:33.510832Z","caller":"traceutil/trace.go:171","msg":"trace[1571026591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1281; }","duration":"156.912842ms","start":"2026-06-24T00:56:33.353909Z","end":"2026-06-24T00:56:33.510822Z","steps":["trace[1571026591] 'agreement among raft nodes before linearized reading' (duration: 156.749897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:33.510833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.962415ms","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-06-24T00:56:33.510864Z","caller":"traceutil/trace.go:171","msg":"trace[487356549] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"156.014187ms","start":"2026-06-24T00:56:33.354841Z","end":"2026-06-24T00:56:33.510856Z","steps":["trace[487356549] 'agreement among raft nodes before linearized reading' (duration: 155.926124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:33.510903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.932635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-24T00:56:33.510951Z","caller":"traceutil/trace.go:171","msg":"trace[598599447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1281; }","duration":"156.023347ms","start":"2026-06-24T00:56:33.354914Z","end":"2026-06-24T00:56:33.510938Z","steps":["trace[598599447] 'agreement among raft nodes before linearized reading' (duration: 155.851932ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:33.676514Z","caller":"traceutil/trace.go:171","msg":"trace[939391245] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"162.264551ms","start":"2026-06-24T00:56:33.514215Z","end":"2026-06-24T00:56:33.67648Z","steps":["trace[939391245] 'process raft request' (duration: 125.638673ms)","trace[939391245] 'compare' (duration: 36.528525ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:56:33.693187Z","caller":"traceutil/trace.go:171","msg":"trace[2146462393] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"178.78358ms","start":"2026-06-24T00:56:33.514392Z","end":"2026-06-24T00:56:33.693175Z","steps":["trace[2146462393] 'process raft request' (duration: 178.604225ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:33.693324Z","caller":"traceutil/trace.go:171","msg":"trace[980565200] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"178.140902ms","start":"2026-06-24T00:56:33.515147Z","end":"2026-06-24T00:56:33.693288Z","steps":["trace[980565200] 'process raft request' (duration: 177.908556ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:33.693102Z","caller":"traceutil/trace.go:171","msg":"trace[1802179610] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1336; }","duration":"171.001424ms","start":"2026-06-24T00:56:33.522088Z","end":"2026-06-24T00:56:33.693089Z","steps":["trace[1802179610] 'read index received' (duration: 117.778604ms)","trace[1802179610] 'applied index is now lower than readState.Index' (duration: 53.22205ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:56:33.693457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.336583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:56:33.693484Z","caller":"traceutil/trace.go:171","msg":"trace[1210762570] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1284; }","duration":"171.416006ms","start":"2026-06-24T00:56:33.52206Z","end":"2026-06-24T00:56:33.693476Z","steps":["trace[1210762570] 'agreement among raft nodes before linearized reading' (duration: 171.337674ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:34.054449Z","caller":"traceutil/trace.go:171","msg":"trace[791261869] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"407.59615ms","start":"2026-06-24T00:56:33.646832Z","end":"2026-06-24T00:56:34.054428Z","steps":["trace[791261869] 'process raft request' (duration: 407.350784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:34.054625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.295912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-06-24T00:56:34.054687Z","caller":"traceutil/trace.go:171","msg":"trace[1826900756] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1285; }","duration":"266.399856ms","start":"2026-06-24T00:56:33.788278Z","end":"2026-06-24T00:56:34.054678Z","steps":["trace[1826900756] 'agreement among raft nodes before linearized reading' (duration: 266.21678ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:34.05445Z","caller":"traceutil/trace.go:171","msg":"trace[735726601] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"266.092146ms","start":"2026-06-24T00:56:33.78832Z","end":"2026-06-24T00:56:34.054412Z","steps":["trace[735726601] 'read index received' (duration: 265.746566ms)","trace[735726601] 'applied index is now lower than readState.Index' (duration: 344.42µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:56:34.055318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:33.646812Z","time spent":"407.788415ms","remote":"127.0.0.1:37838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:56:34.359329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.006826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:56:34.35955Z","caller":"traceutil/trace.go:171","msg":"trace[1196139242] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1340; }","duration":"104.841935ms","start":"2026-06-24T00:56:34.254693Z","end":"2026-06-24T00:56:34.359535Z","steps":["trace[1196139242] 'read index received' (duration: 40.726382ms)","trace[1196139242] 'applied index is now lower than readState.Index' (duration: 64.114663ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:56:34.359619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.935757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:56:34.359639Z","caller":"traceutil/trace.go:171","msg":"trace[1672672798] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1288; }","duration":"105.022249ms","start":"2026-06-24T00:56:34.254612Z","end":"2026-06-24T00:56:34.359634Z","steps":["trace[1672672798] 'agreement among raft nodes before linearized reading' (duration: 104.965868ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:34.359675Z","caller":"traceutil/trace.go:171","msg":"trace[1960662] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"270.292144ms","start":"2026-06-24T00:56:34.089348Z","end":"2026-06-24T00:56:34.359641Z","steps":["trace[1960662] 'process raft request' (duration: 163.906787ms)","trace[1960662] 'compare' (duration: 105.78252ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:56:34.359788Z","caller":"traceutil/trace.go:171","msg":"trace[738111555] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"238.750067ms","start":"2026-06-24T00:56:34.12103Z","end":"2026-06-24T00:56:34.35978Z","steps":["trace[738111555] 'process raft request' (duration: 238.464759ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:34.359897Z","caller":"traceutil/trace.go:171","msg":"trace[1312780455] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"260.998965ms","start":"2026-06-24T00:56:34.098833Z","end":"2026-06-24T00:56:34.359832Z","steps":["trace[1312780455] 'process raft request' (duration: 260.586023ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:42.620677Z","caller":"traceutil/trace.go:171","msg":"trace[1408412878] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"575.807557ms","start":"2026-06-24T00:56:42.044837Z","end":"2026-06-24T00:56:42.620645Z","steps":["trace[1408412878] 'process raft request' (duration: 575.596151ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:42.620897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:56:42.044814Z","time spent":"575.961401ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:56:42.660377Z","caller":"traceutil/trace.go:171","msg":"trace[1081067778] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1407; }","duration":"249.160946ms","start":"2026-06-24T00:56:42.411189Z","end":"2026-06-24T00:56:42.66035Z","steps":["trace[1081067778] 'read index received' (duration: 209.274497ms)","trace[1081067778] 'applied index is now lower than readState.Index' (duration: 39.885139ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:56:42.660515Z","caller":"traceutil/trace.go:171","msg":"trace[196661266] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"267.497136ms","start":"2026-06-24T00:56:42.392983Z","end":"2026-06-24T00:56:42.660481Z","steps":["trace[196661266] 'process raft request' (duration: 267.170077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:42.660645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.435704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-24T00:56:42.660726Z","caller":"traceutil/trace.go:171","msg":"trace[206803301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1352; }","duration":"249.572187ms","start":"2026-06-24T00:56:42.411138Z","end":"2026-06-24T00:56:42.66071Z","steps":["trace[206803301] 'agreement among raft nodes before linearized reading' (duration: 249.362301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:42.66079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.736171ms","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-06-24T00:56:42.660856Z","caller":"traceutil/trace.go:171","msg":"trace[35596447] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1352; }","duration":"159.847683ms","start":"2026-06-24T00:56:42.500991Z","end":"2026-06-24T00:56:42.660839Z","steps":["trace[35596447] 'agreement among raft nodes before linearized reading' (duration: 159.641318ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:44.760559Z","caller":"traceutil/trace.go:171","msg":"trace[1803190122] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"202.819478ms","start":"2026-06-24T00:56:44.557709Z","end":"2026-06-24T00:56:44.760528Z","steps":["trace[1803190122] 'process raft request' (duration: 199.198327ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:56:44.760706Z","caller":"traceutil/trace.go:171","msg":"trace[872754432] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"118.302898ms","start":"2026-06-24T00:56:44.642364Z","end":"2026-06-24T00:56:44.760667Z","steps":["trace[872754432] 'read index received' (duration: 114.518873ms)","trace[872754432] 'applied index is now lower than readState.Index' (duration: 3.779105ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:56:44.760917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.543205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:56:44.760956Z","caller":"traceutil/trace.go:171","msg":"trace[2064783935] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1364; }","duration":"118.645898ms","start":"2026-06-24T00:56:44.642296Z","end":"2026-06-24T00:56:44.760942Z","steps":["trace[2064783935] 'agreement among raft nodes before linearized reading' (duration: 118.527115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:56:44.761041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.748806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:56:44.761083Z","caller":"traceutil/trace.go:171","msg":"trace[489505825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1364; }","duration":"111.804188ms","start":"2026-06-24T00:56:44.649267Z","end":"2026-06-24T00:56:44.761071Z","steps":["trace[489505825] 'agreement among raft nodes before linearized reading' (duration: 111.721945ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:57:24.12345Z","caller":"traceutil/trace.go:171","msg":"trace[1203092826] linearizableReadLoop","detail":"{readStateIndex:1624; appliedIndex:1623; }","duration":"118.670829ms","start":"2026-06-24T00:57:24.004746Z","end":"2026-06-24T00:57:24.123417Z","steps":["trace[1203092826] 'read index received' (duration: 72.088444ms)","trace[1203092826] 'applied index is now lower than readState.Index' (duration: 46.581215ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:57:24.123577Z","caller":"traceutil/trace.go:171","msg":"trace[1282981823] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"120.323855ms","start":"2026-06-24T00:57:24.003208Z","end":"2026-06-24T00:57:24.123532Z","steps":["trace[1282981823] 'process raft request' (duration: 73.567465ms)","trace[1282981823] 'compare' (duration: 46.491692ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:57:24.123724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.963116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-24T00:57:24.123801Z","caller":"traceutil/trace.go:171","msg":"trace[1688771849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1559; }","duration":"119.08498ms","start":"2026-06-24T00:57:24.004698Z","end":"2026-06-24T00:57:24.123783Z","steps":["trace[1688771849] 'agreement among raft nodes before linearized reading' (duration: 118.871625ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:58:00.28732Z","caller":"traceutil/trace.go:171","msg":"trace[1325370681] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"128.927552ms","start":"2026-06-24T00:58:00.158357Z","end":"2026-06-24T00:58:00.287285Z","steps":["trace[1325370681] 'process raft request' (duration: 128.611893ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:58:01.388203Z","caller":"traceutil/trace.go:171","msg":"trace[2080070438] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"299.647111ms","start":"2026-06-24T00:58:01.088527Z","end":"2026-06-24T00:58:01.388174Z","steps":["trace[2080070438] 'process raft request' (duration: 299.364074ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:58:08.290549Z","caller":"traceutil/trace.go:171","msg":"trace[41542270] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"875.230819ms","start":"2026-06-24T00:58:07.415295Z","end":"2026-06-24T00:58:08.290526Z","steps":["trace[41542270] 'process raft request' (duration: 834.46247ms)","trace[41542270] 'compare' (duration: 39.903845ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:58:08.290666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:58:07.415277Z","time spent":"875.331852ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:58:08.293448Z","caller":"traceutil/trace.go:171","msg":"trace[857717311] linearizableReadLoop","detail":"{readStateIndex:1831; appliedIndex:1829; }","duration":"435.426353ms","start":"2026-06-24T00:58:07.857998Z","end":"2026-06-24T00:58:08.293425Z","steps":["trace[857717311] 'read index received' (duration: 391.866837ms)","trace[857717311] 'applied index is now lower than readState.Index' (duration: 43.558666ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:58:08.293586Z","caller":"traceutil/trace.go:171","msg":"trace[748177660] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"877.088431ms","start":"2026-06-24T00:58:07.416467Z","end":"2026-06-24T00:58:08.293555Z","steps":["trace[748177660] 'process raft request' (duration: 876.825474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:58:08.293689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.689721ms","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":"warn","ts":"2026-06-24T00:58:08.293726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:58:07.416442Z","time spent":"877.213963ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:58:08.293741Z","caller":"traceutil/trace.go:171","msg":"trace[1455076685] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1757; }","duration":"435.783174ms","start":"2026-06-24T00:58:07.857944Z","end":"2026-06-24T00:58:08.293727Z","steps":["trace[1455076685] 'agreement among raft nodes before linearized reading' (duration: 435.627709ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:58:08.293718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.428478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T00:58:08.293772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:58:07.857922Z","time spent":"435.843435ms","remote":"127.0.0.1:38006","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-06-24T00:58:08.29379Z","caller":"traceutil/trace.go:171","msg":"trace[1940098027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1757; }","duration":"272.53576ms","start":"2026-06-24T00:58:08.021238Z","end":"2026-06-24T00:58:08.293773Z","steps":["trace[1940098027] 'agreement among raft nodes before linearized reading' (duration: 272.399926ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:58:08.293892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.000836ms","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-06-24T00:58:08.294478Z","caller":"traceutil/trace.go:171","msg":"trace[1815737322] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1757; }","duration":"276.642944ms","start":"2026-06-24T00:58:08.017819Z","end":"2026-06-24T00:58:08.294462Z","steps":["trace[1815737322] 'agreement among raft nodes before linearized reading' (duration: 275.802271ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:58:11.91807Z","caller":"traceutil/trace.go:171","msg":"trace[167485225] linearizableReadLoop","detail":"{readStateIndex:1850; appliedIndex:1849; }","duration":"268.386498ms","start":"2026-06-24T00:58:11.649663Z","end":"2026-06-24T00:58:11.91805Z","steps":["trace[167485225] 'read index received' (duration: 268.254053ms)","trace[167485225] 'applied index is now lower than readState.Index' (duration: 131.415µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:58:11.918212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.534221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:58:11.918256Z","caller":"traceutil/trace.go:171","msg":"trace[1097780754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1775; }","duration":"268.620923ms","start":"2026-06-24T00:58:11.649626Z","end":"2026-06-24T00:58:11.918246Z","steps":["trace[1097780754] 'agreement among raft nodes before linearized reading' (duration: 268.51864ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:58:11.918469Z","caller":"traceutil/trace.go:171","msg":"trace[333043649] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"498.812631ms","start":"2026-06-24T00:58:11.41962Z","end":"2026-06-24T00:58:11.918432Z","steps":["trace[333043649] 'process raft request' (duration: 498.289696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:58:11.918652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:58:11.419589Z","time spent":"498.970245ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:58:14.897802Z","caller":"traceutil/trace.go:171","msg":"trace[710470452] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"114.42853ms","start":"2026-06-24T00:58:14.783348Z","end":"2026-06-24T00:58:14.897776Z","steps":["trace[710470452] 'process raft request' (duration: 114.235514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:58:15.14697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.493722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T00:58:15.147227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.617314ms","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:526"} {"level":"info","ts":"2026-06-24T00:58:15.147359Z","caller":"traceutil/trace.go:171","msg":"trace[167193874] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1791; }","duration":"145.79972ms","start":"2026-06-24T00:58:15.001536Z","end":"2026-06-24T00:58:15.147335Z","steps":["trace[167193874] 'range keys from in-memory index tree' (duration: 145.387908ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:58:15.147157Z","caller":"traceutil/trace.go:171","msg":"trace[1120165707] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1791; }","duration":"140.75796ms","start":"2026-06-24T00:58:15.006374Z","end":"2026-06-24T00:58:15.147132Z","steps":["trace[1120165707] 'count revisions from in-memory index tree' (duration: 140.37802ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:58:52.637728Z","caller":"traceutil/trace.go:171","msg":"trace[2086051566] linearizableReadLoop","detail":"{readStateIndex:2045; appliedIndex:2044; }","duration":"112.470822ms","start":"2026-06-24T00:58:52.525236Z","end":"2026-06-24T00:58:52.637707Z","steps":["trace[2086051566] 'read index received' (duration: 99.766931ms)","trace[2086051566] 'applied index is now lower than readState.Index' (duration: 12.702911ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:58:52.638087Z","caller":"traceutil/trace.go:171","msg":"trace[641358610] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"131.526706ms","start":"2026-06-24T00:58:52.50654Z","end":"2026-06-24T00:58:52.638067Z","steps":["trace[641358610] 'process raft request' (duration: 118.58571ms)","trace[641358610] 'compare' (duration: 12.498654ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:58:52.638329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.086618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-24T00:58:52.638401Z","caller":"traceutil/trace.go:171","msg":"trace[1108778178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1962; }","duration":"113.206431ms","start":"2026-06-24T00:58:52.52518Z","end":"2026-06-24T00:58:52.638386Z","steps":["trace[1108778178] 'agreement among raft nodes before linearized reading' (duration: 112.856752ms)"],"step_count":1}