{"level":"warn","ts":"2026-04-10T13:41:15.62084Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-10T13:41:15.62095Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-10T13:41:15.621042Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-10T13:41:15.621061Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-10T13:41:15.62109Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-10T13:41:15.6255Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-10T13:41:15.62562Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-10T13:41:15.632053Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.189522ms"} {"level":"info","ts":"2026-04-10T13:41:15.640236Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-10T13:41:15.64035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-10T13:41:15.640384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-10T13:41:15.640406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-10T13:41:15.640413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-10T13:41:15.640461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-10T13:41:15.649421Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-10T13:41:15.651831Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-10T13:41:15.654225Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-10T13:41:15.658755Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-10T13:41:15.659078Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-10T13:41:15.659194Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-10T13:41:15.65921Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-10T13:41:15.660867Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-10T13:41:15.661368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-10T13:41:15.661618Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-10T13:41:15.668235Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-10T13:41:15.668553Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-10T13:41:15.668586Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-10T13:41:15.669348Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-10T13:41:15.669388Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-10T13:41:15.841101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-10T13:41:15.841163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-10T13:41:15.8412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-10T13:41:15.841221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-10T13:41:15.84145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-10T13:41:15.841538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-10T13:41:15.841564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-10T13:41:15.842581Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-10T13:41:15.842727Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T13:41:15.842897Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T13:41:15.843064Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T13:41:15.843265Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-10T13:41:15.843348Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-10T13:41:15.843872Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T13:41:15.84405Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T13:41:15.844129Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T13:41:15.847129Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-04-10T13:41:15.847358Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-10T13:41:20.758155Z","caller":"traceutil/trace.go:171","msg":"trace[2016081931] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"130.124449ms","start":"2026-04-10T13:41:20.627999Z","end":"2026-04-10T13:41:20.758123Z","steps":["trace[2016081931] 'process raft request' (duration: 129.953133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:21.648231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.566269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-2bh7xj\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:41:21.648638Z","caller":"traceutil/trace.go:171","msg":"trace[138859447] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-2bh7xj; range_end:; response_count:0; response_revision:215; }","duration":"886.067197ms","start":"2026-04-10T13:41:20.762551Z","end":"2026-04-10T13:41:21.648618Z","steps":["trace[138859447] 'range keys from in-memory index tree' (duration: 885.488765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:21.648744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:20.762529Z","time spent":"886.192882ms","remote":"127.0.0.1:45568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-2bh7xj\" "} {"level":"info","ts":"2026-04-10T13:41:21.748263Z","caller":"traceutil/trace.go:171","msg":"trace[837052073] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"831.456674ms","start":"2026-04-10T13:41:20.916772Z","end":"2026-04-10T13:41:21.748228Z","steps":["trace[837052073] 'process raft request' (duration: 831.233576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:21.748911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:20.916743Z","time spent":"831.605399ms","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:21.773652Z","caller":"traceutil/trace.go:171","msg":"trace[409940178] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"119.965093ms","start":"2026-04-10T13:41:21.653659Z","end":"2026-04-10T13:41:21.773624Z","steps":["trace[409940178] 'process raft request' (duration: 96.934359ms)","trace[409940178] 'compare' (duration: 22.771734ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:41:22.098596Z","caller":"traceutil/trace.go:171","msg":"trace[698913065] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"109.381863ms","start":"2026-04-10T13:41:21.989181Z","end":"2026-04-10T13:41:22.098563Z","steps":["trace[698913065] 'process raft request' (duration: 50.121183ms)","trace[698913065] 'compare' (duration: 59.101644ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:41:22.458964Z","caller":"traceutil/trace.go:171","msg":"trace[1352100164] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"356.461802ms","start":"2026-04-10T13:41:22.102461Z","end":"2026-04-10T13:41:22.458922Z","steps":["trace[1352100164] 'process raft request' (duration: 351.481391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:22.459477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.102348Z","time spent":"356.790704ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:41:22.458954Z","caller":"traceutil/trace.go:171","msg":"trace[2142776422] linearizableReadLoop","detail":"{readStateIndex:246; appliedIndex:243; }","duration":"355.458376ms","start":"2026-04-10T13:41:22.103476Z","end":"2026-04-10T13:41:22.458934Z","steps":["trace[2142776422] 'read index received' (duration: 12.471503ms)","trace[2142776422] 'applied index is now lower than readState.Index' (duration: 342.985703ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:41:22.459043Z","caller":"traceutil/trace.go:171","msg":"trace[407192197] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"356.266445ms","start":"2026-04-10T13:41:22.102749Z","end":"2026-04-10T13:41:22.459016Z","steps":["trace[407192197] 'process raft request' (duration: 356.111869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:22.459075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.58416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-10T13:41:22.459767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.102733Z","time spent":"356.94197ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:41:22.459913Z","caller":"traceutil/trace.go:171","msg":"trace[1269313045] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:240; }","duration":"356.363258ms","start":"2026-04-10T13:41:22.103435Z","end":"2026-04-10T13:41:22.459799Z","steps":["trace[1269313045] 'agreement among raft nodes before linearized reading' (duration: 355.574269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:22.46001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.103422Z","time spent":"356.550015ms","remote":"127.0.0.1:45590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-10T13:41:22.716381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.594039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:41:22.717611Z","caller":"traceutil/trace.go:171","msg":"trace[296221134] linearizableReadLoop","detail":"{readStateIndex:254; appliedIndex:246; }","duration":"257.910385ms","start":"2026-04-10T13:41:22.459645Z","end":"2026-04-10T13:41:22.717556Z","steps":["trace[296221134] 'read index received' (duration: 131.440217ms)","trace[296221134] 'applied index is now lower than readState.Index' (duration: 126.468168ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:41:22.718047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.392734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-10T13:41:22.71828Z","caller":"traceutil/trace.go:171","msg":"trace[1525747940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:245; }","duration":"599.503078ms","start":"2026-04-10T13:41:22.118596Z","end":"2026-04-10T13:41:22.718099Z","steps":["trace[1525747940] 'agreement among raft nodes before linearized reading' (duration: 599.072252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:22.718381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.118578Z","time spent":"599.782138ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-10T13:41:22.71876Z","caller":"traceutil/trace.go:171","msg":"trace[374114038] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"599.125635ms","start":"2026-04-10T13:41:22.119604Z","end":"2026-04-10T13:41:22.71873Z","steps":["trace[374114038] 'process raft request' (duration: 471.467014ms)","trace[374114038] 'compare' (duration: 124.453454ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:41:22.718932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.119574Z","time spent":"599.26352ms","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:22.71922Z","caller":"traceutil/trace.go:171","msg":"trace[204264776] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"473.095693ms","start":"2026-04-10T13:41:22.246108Z","end":"2026-04-10T13:41:22.719203Z","steps":["trace[204264776] 'process raft request' (duration: 470.649604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:22.719339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.246087Z","time spent":"473.171866ms","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:22.719522Z","caller":"traceutil/trace.go:171","msg":"trace[974324037] transaction","detail":"{read_only:false; number_of_response:0; response_revision:242; }","duration":"376.001121ms","start":"2026-04-10T13:41:22.343494Z","end":"2026-04-10T13:41:22.719496Z","steps":["trace[974324037] 'process raft request' (duration: 373.396757ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:22.7196Z","caller":"traceutil/trace.go:171","msg":"trace[231951870] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"357.625925ms","start":"2026-04-10T13:41:22.361877Z","end":"2026-04-10T13:41:22.719503Z","steps":["trace[231951870] 'process raft request' (duration: 355.120984ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:22.719638Z","caller":"traceutil/trace.go:171","msg":"trace[1529740748] transaction","detail":"{read_only:false; number_of_response:0; response_revision:243; }","duration":"357.575532ms","start":"2026-04-10T13:41:22.362031Z","end":"2026-04-10T13:41:22.719607Z","steps":["trace[1529740748] 'process raft request' (duration: 355.128574ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:22.719518Z","caller":"traceutil/trace.go:171","msg":"trace[1375360307] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"357.356334ms","start":"2026-04-10T13:41:22.362133Z","end":"2026-04-10T13:41:22.719489Z","steps":["trace[1375360307] 'process raft request' (duration: 355.076062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:22.719728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.34346Z","time spent":"376.199498ms","remote":"127.0.0.1:45662","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:22.719728Z","caller":"traceutil/trace.go:171","msg":"trace[1037355152] transaction","detail":"{read_only:false; number_of_response:0; response_revision:242; }","duration":"358.149663ms","start":"2026-04-10T13:41:22.361559Z","end":"2026-04-10T13:41:22.719709Z","steps":["trace[1037355152] 'process raft request' (duration: 355.395823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:22.719751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.362023Z","time spent":"357.668385ms","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:41:22.719555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.088165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T13:41:22.71982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.361535Z","time spent":"358.247987ms","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:41:22.719871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.362123Z","time spent":"357.625705ms","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:22.720018Z","caller":"traceutil/trace.go:171","msg":"trace[724127782] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"350.32412ms","start":"2026-04-10T13:41:22.369675Z","end":"2026-04-10T13:41:22.719999Z","steps":["trace[724127782] 'process raft request' (duration: 347.707515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:22.720118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.369654Z","time spent":"350.417913ms","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:41:22.720249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.410467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-10T13:41:22.720306Z","caller":"traceutil/trace.go:171","msg":"trace[691723236] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:245; }","duration":"475.473419ms","start":"2026-04-10T13:41:22.244815Z","end":"2026-04-10T13:41:22.720288Z","steps":["trace[691723236] 'agreement among raft nodes before linearized reading' (duration: 475.362915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:22.72029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.280617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T13:41:22.720358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.244809Z","time spent":"475.540971ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-10T13:41:22.720382Z","caller":"traceutil/trace.go:171","msg":"trace[698874857] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:245; }","duration":"549.399421ms","start":"2026-04-10T13:41:22.17096Z","end":"2026-04-10T13:41:22.720359Z","steps":["trace[698874857] 'agreement among raft nodes before linearized reading' (duration: 549.267616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:22.720453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.170941Z","time spent":"549.489454ms","remote":"127.0.0.1:45982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"info","ts":"2026-04-10T13:41:22.719895Z","caller":"traceutil/trace.go:171","msg":"trace[1310241842] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:245; }","duration":"475.440868ms","start":"2026-04-10T13:41:22.244412Z","end":"2026-04-10T13:41:22.719853Z","steps":["trace[1310241842] 'agreement among raft nodes before linearized reading' (duration: 475.074695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:22.721382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.244394Z","time spent":"476.964603ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-10T13:41:22.721872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.361857Z","time spent":"359.975809ms","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:22.722782Z","caller":"traceutil/trace.go:171","msg":"trace[1341031500] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"259.504264ms","start":"2026-04-10T13:41:22.463258Z","end":"2026-04-10T13:41:22.722762Z","steps":["trace[1341031500] 'process raft request' (duration: 259.12314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:23.216617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.778586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:41:23.216764Z","caller":"traceutil/trace.go:171","msg":"trace[916282861] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"485.066877ms","start":"2026-04-10T13:41:22.731679Z","end":"2026-04-10T13:41:23.216746Z","steps":["trace[916282861] 'process raft request' (duration: 266.072223ms)","trace[916282861] 'compare' (duration: 218.642281ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:41:23.216837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.731664Z","time spent":"485.143419ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:41:23.217707Z","caller":"traceutil/trace.go:171","msg":"trace[839347759] linearizableReadLoop","detail":"{readStateIndex:258; appliedIndex:255; }","duration":"485.359737ms","start":"2026-04-10T13:41:22.732328Z","end":"2026-04-10T13:41:23.217688Z","steps":["trace[839347759] 'read index received' (duration: 12.205363ms)","trace[839347759] 'applied index is now lower than readState.Index' (duration: 473.153354ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:41:23.217837Z","caller":"traceutil/trace.go:171","msg":"trace[1791096367] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"482.15189ms","start":"2026-04-10T13:41:22.735669Z","end":"2026-04-10T13:41:23.217821Z","steps":["trace[1791096367] 'process raft request' (duration: 481.897201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:23.217927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.735624Z","time spent":"482.254194ms","remote":"127.0.0.1:45662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4183,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:41:23.217905Z","caller":"traceutil/trace.go:171","msg":"trace[757960328] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"486.032671ms","start":"2026-04-10T13:41:22.731815Z","end":"2026-04-10T13:41:23.217848Z","steps":["trace[757960328] 'process raft request' (duration: 484.89044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:23.218024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.52858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:41:23.218081Z","caller":"traceutil/trace.go:171","msg":"trace[1688297653] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:251; }","duration":"483.620873ms","start":"2026-04-10T13:41:22.734445Z","end":"2026-04-10T13:41:23.218066Z","steps":["trace[1688297653] 'agreement among raft nodes before linearized reading' (duration: 483.490748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:23.218077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.731794Z","time spent":"486.207568ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:41:23.218126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.734439Z","time spent":"483.681296ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"info","ts":"2026-04-10T13:41:23.218147Z","caller":"traceutil/trace.go:171","msg":"trace[996977373] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"468.186883ms","start":"2026-04-10T13:41:22.749944Z","end":"2026-04-10T13:41:23.218131Z","steps":["trace[996977373] 'process raft request' (duration: 467.698236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:23.218227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.74992Z","time spent":"468.267147ms","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:41:23.218276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.487263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:41:23.218299Z","caller":"traceutil/trace.go:171","msg":"trace[137147884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:251; }","duration":"431.556185ms","start":"2026-04-10T13:41:22.786736Z","end":"2026-04-10T13:41:23.218292Z","steps":["trace[137147884] 'agreement among raft nodes before linearized reading' (duration: 431.521014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:23.218314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.78671Z","time spent":"431.600746ms","remote":"127.0.0.1:45484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T13:41:23.218459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.118274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-10T13:41:23.218526Z","caller":"traceutil/trace.go:171","msg":"trace[2083829363] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:251; }","duration":"486.182636ms","start":"2026-04-10T13:41:22.732323Z","end":"2026-04-10T13:41:23.218505Z","steps":["trace[2083829363] 'agreement among raft nodes before linearized reading' (duration: 485.558484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:23.218584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:22.732314Z","time spent":"486.256759ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-04-10T13:41:23.628526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.211844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:41:23.628631Z","caller":"traceutil/trace.go:171","msg":"trace[957895391] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:260; }","duration":"405.857176ms","start":"2026-04-10T13:41:23.222757Z","end":"2026-04-10T13:41:23.628615Z","steps":["trace[957895391] 'read index received' (duration: 121.505497ms)","trace[957895391] 'applied index is now lower than readState.Index' (duration: 284.348199ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:41:23.628717Z","caller":"traceutil/trace.go:171","msg":"trace[1622331115] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"406.104655ms","start":"2026-04-10T13:41:23.22256Z","end":"2026-04-10T13:41:23.628665Z","steps":["trace[1622331115] 'process raft request' (duration: 121.685244ms)","trace[1622331115] 'compare' (duration: 284.060748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:41:23.62876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.004612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-10T13:41:23.628803Z","caller":"traceutil/trace.go:171","msg":"trace[133951478] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:252; }","duration":"406.076215ms","start":"2026-04-10T13:41:23.222712Z","end":"2026-04-10T13:41:23.628788Z","steps":["trace[133951478] 'agreement among raft nodes before linearized reading' (duration: 405.971941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:23.628836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:23.222696Z","time spent":"406.132217ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-04-10T13:41:23.628868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:23.222533Z","time spent":"406.266431ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":597,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:23.637066Z","caller":"traceutil/trace.go:171","msg":"trace[16922030] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"413.586424ms","start":"2026-04-10T13:41:23.223452Z","end":"2026-04-10T13:41:23.637039Z","steps":["trace[16922030] 'process raft request' (duration: 413.261292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:23.637199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:23.223435Z","time spent":"413.690318ms","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:23.637197Z","caller":"traceutil/trace.go:171","msg":"trace[1605664904] transaction","detail":"{read_only:false; number_of_response:0; response_revision:253; }","duration":"364.478579ms","start":"2026-04-10T13:41:23.2727Z","end":"2026-04-10T13:41:23.637179Z","steps":["trace[1605664904] 'process raft request' (duration: 364.334424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:23.637297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:23.272669Z","time spent":"364.593213ms","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:23.637189Z","caller":"traceutil/trace.go:171","msg":"trace[819525858] transaction","detail":"{read_only:false; number_of_response:0; response_revision:253; }","duration":"365.186994ms","start":"2026-04-10T13:41:23.271972Z","end":"2026-04-10T13:41:23.637159Z","steps":["trace[819525858] 'process raft request' (duration: 364.986607ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:23.637357Z","caller":"traceutil/trace.go:171","msg":"trace[1180054747] transaction","detail":"{read_only:false; number_of_response:0; response_revision:253; }","duration":"364.381496ms","start":"2026-04-10T13:41:23.272942Z","end":"2026-04-10T13:41:23.637324Z","steps":["trace[1180054747] 'process raft request' (duration: 364.135637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:23.637472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:23.272924Z","time spent":"364.49379ms","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:41:23.63759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:23.271952Z","time spent":"365.456014ms","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:23.6376Z","caller":"traceutil/trace.go:171","msg":"trace[862313928] transaction","detail":"{read_only:false; number_of_response:0; response_revision:253; }","duration":"363.92961ms","start":"2026-04-10T13:41:23.273655Z","end":"2026-04-10T13:41:23.637585Z","steps":["trace[862313928] 'process raft request' (duration: 363.792245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:23.637682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.246479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-04-10T13:41:23.637692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:23.273638Z","time spent":"364.012913ms","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:23.63774Z","caller":"traceutil/trace.go:171","msg":"trace[572602095] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:253; }","duration":"367.329722ms","start":"2026-04-10T13:41:23.270395Z","end":"2026-04-10T13:41:23.637725Z","steps":["trace[572602095] 'agreement among raft nodes before linearized reading' (duration: 367.198667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:23.637779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:23.270377Z","time spent":"367.392894ms","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2942,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-10T13:41:28.193869Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.304718221s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-10T13:41:29.653587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459192838s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:41:29.653773Z","caller":"traceutil/trace.go:171","msg":"trace[1074137516] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"2.794953234s","start":"2026-04-10T13:41:26.858787Z","end":"2026-04-10T13:41:29.65374Z","steps":["trace[1074137516] 'process raft request' (duration: 1.335508947s)","trace[1074137516] 'compare' (duration: 1.458253507s)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:41:29.653836Z","caller":"traceutil/trace.go:171","msg":"trace[1418216141] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"1.211602657s","start":"2026-04-10T13:41:28.442205Z","end":"2026-04-10T13:41:29.653807Z","steps":["trace[1418216141] 'process raft request' (duration: 1.211504883s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:29.653932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:26.858773Z","time spent":"2.795069949s","remote":"127.0.0.1:45876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:41:29.653944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:28.442179Z","time spent":"1.21170938s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:41:29.654021Z","caller":"traceutil/trace.go:171","msg":"trace[1151546464] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:334; }","duration":"2.769244715s","start":"2026-04-10T13:41:26.884736Z","end":"2026-04-10T13:41:29.653981Z","steps":["trace[1151546464] 'read index received' (duration: 4.396234ms)","trace[1151546464] 'applied index is now lower than readState.Index' (duration: 2.764844701s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:41:29.654276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.769524884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-10T13:41:29.654342Z","caller":"traceutil/trace.go:171","msg":"trace[1655827110] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:324; }","duration":"2.769645629s","start":"2026-04-10T13:41:26.884678Z","end":"2026-04-10T13:41:29.654324Z","steps":["trace[1655827110] 'agreement among raft nodes before linearized reading' (duration: 2.769508604s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:29.65439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:26.884651Z","time spent":"2.769726512s","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-04-10T13:41:29.654398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.753770196s","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-04-10T13:41:29.654452Z","caller":"traceutil/trace.go:171","msg":"trace[1278749660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:324; }","duration":"1.753850829s","start":"2026-04-10T13:41:27.900586Z","end":"2026-04-10T13:41:29.654437Z","steps":["trace[1278749660] 'agreement among raft nodes before linearized reading' (duration: 1.753755316s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:29.654515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:27.900568Z","time spent":"1.753934511s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T13:41:29.654546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.376079406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-10T13:41:29.65462Z","caller":"traceutil/trace.go:171","msg":"trace[1694912994] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:324; }","duration":"2.376141189s","start":"2026-04-10T13:41:27.278436Z","end":"2026-04-10T13:41:29.654578Z","steps":["trace[1694912994] 'agreement among raft nodes before linearized reading' (duration: 2.376049126s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:29.654677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:27.278421Z","time spent":"2.376245553s","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-10T13:41:29.654735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168541768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-10T13:41:29.654789Z","caller":"traceutil/trace.go:171","msg":"trace[1777690099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:324; }","duration":"1.168641181s","start":"2026-04-10T13:41:28.486134Z","end":"2026-04-10T13:41:29.654775Z","steps":["trace[1777690099] 'agreement among raft nodes before linearized reading' (duration: 1.168546968s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:29.654837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:28.48611Z","time spent":"1.168716854s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T13:41:29.654606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.399407ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:41:29.655Z","caller":"traceutil/trace.go:171","msg":"trace[1301714098] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:324; }","duration":"207.895615ms","start":"2026-04-10T13:41:29.44708Z","end":"2026-04-10T13:41:29.654975Z","steps":["trace[1301714098] 'agreement among raft nodes before linearized reading' (duration: 207.323006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:29.655077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.40591082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-10T13:41:29.655127Z","caller":"traceutil/trace.go:171","msg":"trace[801894431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:324; }","duration":"2.406000363s","start":"2026-04-10T13:41:27.249116Z","end":"2026-04-10T13:41:29.655116Z","steps":["trace[801894431] 'agreement among raft nodes before linearized reading' (duration: 2.40592087s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:29.655156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:27.249093Z","time spent":"2.406056725s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T13:41:29.655293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.621450396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-10T13:41:29.65535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.671782137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-10T13:41:29.655356Z","caller":"traceutil/trace.go:171","msg":"trace[1477993533] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:324; }","duration":"2.62155019s","start":"2026-04-10T13:41:27.033791Z","end":"2026-04-10T13:41:29.655342Z","steps":["trace[1477993533] 'agreement among raft nodes before linearized reading' (duration: 2.621452646s)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:29.65538Z","caller":"traceutil/trace.go:171","msg":"trace[1746759428] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:324; }","duration":"2.671842759s","start":"2026-04-10T13:41:26.983528Z","end":"2026-04-10T13:41:29.655371Z","steps":["trace[1746759428] 'agreement among raft nodes before linearized reading' (duration: 2.671771636s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:29.655406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:26.983507Z","time spent":"2.671893321s","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-04-10T13:41:29.655464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.570956408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-10T13:41:29.655515Z","caller":"traceutil/trace.go:171","msg":"trace[264578073] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:324; }","duration":"2.571065702s","start":"2026-04-10T13:41:27.084437Z","end":"2026-04-10T13:41:29.655502Z","steps":["trace[264578073] 'agreement among raft nodes before linearized reading' (duration: 2.570989069s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:29.655401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:27.033769Z","time spent":"2.621620832s","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-04-10T13:41:29.655557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:27.084402Z","time spent":"2.571143675s","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-10T13:41:29.655046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171775889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-10T13:41:29.655706Z","caller":"traceutil/trace.go:171","msg":"trace[1970528571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:324; }","duration":"1.172486143s","start":"2026-04-10T13:41:28.483202Z","end":"2026-04-10T13:41:29.655688Z","steps":["trace[1970528571] 'agreement among raft nodes before linearized reading' (duration: 1.17179461s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:29.65582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:28.483167Z","time spent":"1.172633538s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-10T13:41:29.654416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.719733044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-10T13:41:29.655983Z","caller":"traceutil/trace.go:171","msg":"trace[1076100367] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:324; }","duration":"2.72139204s","start":"2026-04-10T13:41:26.934577Z","end":"2026-04-10T13:41:29.655969Z","steps":["trace[1076100367] 'agreement among raft nodes before linearized reading' (duration: 2.719698822s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:29.656058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:26.934559Z","time spent":"2.721486124s","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-04-10T13:41:30.663242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.915686ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-04-10T13:41:30.663478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.657718Z","time spent":"1.005754889s","remote":"127.0.0.1:45510","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-10T13:41:30.664486Z","caller":"traceutil/trace.go:171","msg":"trace[2052042527] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"1.002240938s","start":"2026-04-10T13:41:29.662217Z","end":"2026-04-10T13:41:30.664458Z","steps":["trace[2052042527] 'process raft request' (duration: 1.001180513s)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:30.664727Z","caller":"traceutil/trace.go:171","msg":"trace[1614119232] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"1.002408295s","start":"2026-04-10T13:41:29.66229Z","end":"2026-04-10T13:41:30.664698Z","steps":["trace[1614119232] 'process raft request' (duration: 1.001851406s)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:30.664754Z","caller":"traceutil/trace.go:171","msg":"trace[1677286795] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"1.002386684s","start":"2026-04-10T13:41:29.662342Z","end":"2026-04-10T13:41:30.664729Z","steps":["trace[1677286795] 'process raft request' (duration: 1.002215538s)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:30.664798Z","caller":"traceutil/trace.go:171","msg":"trace[969901541] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"1.002423374s","start":"2026-04-10T13:41:29.66236Z","end":"2026-04-10T13:41:30.664784Z","steps":["trace[969901541] 'process raft request' (duration: 1.00229744s)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:30.664833Z","caller":"traceutil/trace.go:171","msg":"trace[788899369] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"1.002088284s","start":"2026-04-10T13:41:29.662708Z","end":"2026-04-10T13:41:30.664796Z","steps":["trace[788899369] 'process raft request' (duration: 1.002018112s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:30.664861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.662337Z","time spent":"1.002487247s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:41:30.664864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.662283Z","time spent":"1.002520078s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:41:30.664915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.662665Z","time spent":"1.002204877s","remote":"127.0.0.1:45648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:30.66509Z","caller":"traceutil/trace.go:171","msg":"trace[670085184] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"1.000974045s","start":"2026-04-10T13:41:29.664098Z","end":"2026-04-10T13:41:30.665072Z","steps":["trace[670085184] 'process raft request' (duration: 1.000662635s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:30.665099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.662335Z","time spent":"1.0025785s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:41:30.665285Z","caller":"traceutil/trace.go:171","msg":"trace[1668254007] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"1.000008273s","start":"2026-04-10T13:41:29.665265Z","end":"2026-04-10T13:41:30.665273Z","steps":["trace[1668254007] 'process raft request' (duration: 999.799586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:30.665367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.665191Z","time spent":"1.000144327s","remote":"127.0.0.1:46002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:30.665386Z","caller":"traceutil/trace.go:171","msg":"trace[227400044] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"998.227772ms","start":"2026-04-10T13:41:29.667149Z","end":"2026-04-10T13:41:30.665377Z","steps":["trace[227400044] 'process raft request' (duration: 998.200172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:30.665337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.662202Z","time spent":"1.002357803s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:41:30.665418Z","caller":"traceutil/trace.go:171","msg":"trace[1348573612] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"1.000105597s","start":"2026-04-10T13:41:29.665301Z","end":"2026-04-10T13:41:30.665407Z","steps":["trace[1348573612] 'process raft request' (duration: 999.889599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:30.665483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.665287Z","time spent":"1.000163198s","remote":"127.0.0.1:45782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:41:30.665164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.664083Z","time spent":"1.001045068s","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:30.665547Z","caller":"traceutil/trace.go:171","msg":"trace[1741501001] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"998.709008ms","start":"2026-04-10T13:41:29.666831Z","end":"2026-04-10T13:41:30.66554Z","steps":["trace[1741501001] 'process raft request' (duration: 998.48248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:30.665583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.666805Z","time spent":"998.75902ms","remote":"127.0.0.1:45662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4411,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:41:30.665372Z","caller":"traceutil/trace.go:171","msg":"trace[189237979] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"999.736624ms","start":"2026-04-10T13:41:29.665618Z","end":"2026-04-10T13:41:30.665354Z","steps":["trace[189237979] 'process raft request' (duration: 999.600939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:30.665426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.667134Z","time spent":"998.272263ms","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4781,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:41:30.665667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.665605Z","time spent":"1.000013323s","remote":"127.0.0.1:45876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:41:30.665169Z","caller":"traceutil/trace.go:171","msg":"trace[1385881384] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"1.000671805s","start":"2026-04-10T13:41:29.664485Z","end":"2026-04-10T13:41:30.665157Z","steps":["trace[1385881384] 'process raft request' (duration: 1.000441757s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:30.665782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.664471Z","time spent":"1.001284316s","remote":"127.0.0.1:46004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:41:30.66593Z","caller":"traceutil/trace.go:171","msg":"trace[1017118216] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"999.826597ms","start":"2026-04-10T13:41:29.666085Z","end":"2026-04-10T13:41:30.665912Z","steps":["trace[1017118216] 'process raft request' (duration: 999.193355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:30.666028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:29.66606Z","time spent":"999.91447ms","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:41:32.040392Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.018732452s","expected-duration":"1s"} {"level":"info","ts":"2026-04-10T13:41:32.040791Z","caller":"traceutil/trace.go:171","msg":"trace[874415613] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"253.944501ms","start":"2026-04-10T13:41:31.786821Z","end":"2026-04-10T13:41:32.040766Z","steps":["trace[874415613] 'read index received' (duration: 253.769515ms)","trace[874415613] 'applied index is now lower than readState.Index' (duration: 173.555µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:41:32.040939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.108866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:41:32.040937Z","caller":"traceutil/trace.go:171","msg":"trace[1370137610] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"1.019376143s","start":"2026-04-10T13:41:31.021522Z","end":"2026-04-10T13:41:32.040898Z","steps":["trace[1370137610] 'process raft request' (duration: 1.019081773s)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:32.040979Z","caller":"traceutil/trace.go:171","msg":"trace[1087752486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:362; }","duration":"254.198669ms","start":"2026-04-10T13:41:31.786768Z","end":"2026-04-10T13:41:32.040967Z","steps":["trace[1087752486] 'agreement among raft nodes before linearized reading' (duration: 254.090696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:32.041136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:31.021455Z","time spent":"1.019547319s","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:41:32.260107Z","caller":"traceutil/trace.go:171","msg":"trace[1347031218] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"205.853043ms","start":"2026-04-10T13:41:32.054224Z","end":"2026-04-10T13:41:32.260078Z","steps":["trace[1347031218] 'process raft request' (duration: 181.686671ms)","trace[1347031218] 'compare' (duration: 24.045578ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:41:34.85073Z","caller":"traceutil/trace.go:171","msg":"trace[513341726] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"126.630726ms","start":"2026-04-10T13:41:34.724071Z","end":"2026-04-10T13:41:34.850701Z","steps":["trace[513341726] 'process raft request' (duration: 125.5253ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:34.850813Z","caller":"traceutil/trace.go:171","msg":"trace[1488742893] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"124.349831ms","start":"2026-04-10T13:41:34.726445Z","end":"2026-04-10T13:41:34.850794Z","steps":["trace[1488742893] 'process raft request' (duration: 124.157004ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:34.851105Z","caller":"traceutil/trace.go:171","msg":"trace[146491930] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"124.096222ms","start":"2026-04-10T13:41:34.726633Z","end":"2026-04-10T13:41:34.85073Z","steps":["trace[146491930] 'process raft request' (duration: 124.02627ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:37.155252Z","caller":"traceutil/trace.go:171","msg":"trace[1420042850] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:395; }","duration":"368.125739ms","start":"2026-04-10T13:41:36.787098Z","end":"2026-04-10T13:41:37.155223Z","steps":["trace[1420042850] 'read index received' (duration: 367.990455ms)","trace[1420042850] 'applied index is now lower than readState.Index' (duration: 134.394µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:41:37.155477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.365517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:41:37.155522Z","caller":"traceutil/trace.go:171","msg":"trace[173208398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:380; }","duration":"368.425229ms","start":"2026-04-10T13:41:36.787084Z","end":"2026-04-10T13:41:37.155509Z","steps":["trace[173208398] 'agreement among raft nodes before linearized reading' (duration: 368.277224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:37.155558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:36.787029Z","time spent":"368.519491ms","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T13:41:37.155589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.808929ms","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-04-10T13:41:37.155669Z","caller":"traceutil/trace.go:171","msg":"trace[342041226] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"384.163963ms","start":"2026-04-10T13:41:36.771486Z","end":"2026-04-10T13:41:37.15565Z","steps":["trace[342041226] 'process raft request' (duration: 383.576914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:37.155668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.479499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-10T13:41:37.155697Z","caller":"traceutil/trace.go:171","msg":"trace[1123156209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:380; }","duration":"298.947213ms","start":"2026-04-10T13:41:36.8567Z","end":"2026-04-10T13:41:37.155648Z","steps":["trace[1123156209] 'agreement among raft nodes before linearized reading' (duration: 298.763248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:37.155698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.727856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-10T13:41:37.155757Z","caller":"traceutil/trace.go:171","msg":"trace[1060236386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:380; }","duration":"298.648164ms","start":"2026-04-10T13:41:36.857083Z","end":"2026-04-10T13:41:37.155731Z","steps":["trace[1060236386] 'agreement among raft nodes before linearized reading' (duration: 298.425877ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:37.155762Z","caller":"traceutil/trace.go:171","msg":"trace[1370831461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:380; }","duration":"298.81407ms","start":"2026-04-10T13:41:36.85693Z","end":"2026-04-10T13:41:37.155744Z","steps":["trace[1370831461] 'agreement among raft nodes before linearized reading' (duration: 298.688256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:37.155786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:36.771468Z","time spent":"384.245356ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:41:37.60612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.556418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:41:37.606517Z","caller":"traceutil/trace.go:171","msg":"trace[396869633] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"447.376699ms","start":"2026-04-10T13:41:37.15912Z","end":"2026-04-10T13:41:37.606496Z","steps":["trace[396869633] 'process raft request' (duration: 54.359436ms)","trace[396869633] 'compare' (duration: 392.328461ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:41:37.606641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:37.159095Z","time spent":"447.503893ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:41:39.994442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.819331478s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:41:39.994617Z","caller":"traceutil/trace.go:171","msg":"trace[1860511553] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"2.834618248s","start":"2026-04-10T13:41:37.15998Z","end":"2026-04-10T13:41:39.994598Z","steps":["trace[1860511553] 'process raft request' (duration: 1.015048303s)","trace[1860511553] 'compare' (duration: 1.818634546s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:41:39.994684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:37.159958Z","time spent":"2.83469471s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:41:39.994725Z","caller":"traceutil/trace.go:171","msg":"trace[1003178479] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:397; }","duration":"1.208191521s","start":"2026-04-10T13:41:38.786511Z","end":"2026-04-10T13:41:39.994702Z","steps":["trace[1003178479] 'read index received' (duration: 41.212µs)","trace[1003178479] 'applied index is now lower than readState.Index' (duration: 1.208142249s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:41:39.994826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208319045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:41:39.994846Z","caller":"traceutil/trace.go:171","msg":"trace[1308008417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:383; }","duration":"1.208378776s","start":"2026-04-10T13:41:38.786461Z","end":"2026-04-10T13:41:39.994839Z","steps":["trace[1308008417] 'agreement among raft nodes before linearized reading' (duration: 1.208339135s)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:39.994816Z","caller":"traceutil/trace.go:171","msg":"trace[1235175291] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"2.834770702s","start":"2026-04-10T13:41:37.160017Z","end":"2026-04-10T13:41:39.994788Z","steps":["trace[1235175291] 'process raft request' (duration: 2.834532264s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:39.994865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:38.786438Z","time spent":"1.208422978s","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T13:41:39.994947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:37.160001Z","time spent":"2.834871826s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:41:39.994995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.519325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-10T13:41:39.995015Z","caller":"traceutil/trace.go:171","msg":"trace[1832054443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:383; }","duration":"835.585147ms","start":"2026-04-10T13:41:39.159424Z","end":"2026-04-10T13:41:39.995009Z","steps":["trace[1832054443] 'agreement among raft nodes before linearized reading' (duration: 835.541645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:39.995032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:39.159401Z","time spent":"835.627278ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T13:41:39.995091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.686664ms","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-04-10T13:41:39.995106Z","caller":"traceutil/trace.go:171","msg":"trace[1608345902] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:383; }","duration":"384.720106ms","start":"2026-04-10T13:41:39.610381Z","end":"2026-04-10T13:41:39.995101Z","steps":["trace[1608345902] 'agreement among raft nodes before linearized reading' (duration: 384.694345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:39.995111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.006717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-10T13:41:39.99513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:39.610367Z","time spent":"384.759416ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-10T13:41:39.995149Z","caller":"traceutil/trace.go:171","msg":"trace[1742842829] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:383; }","duration":"411.082369ms","start":"2026-04-10T13:41:39.584054Z","end":"2026-04-10T13:41:39.995136Z","steps":["trace[1742842829] 'agreement among raft nodes before linearized reading' (duration: 411.022667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:41:39.995178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:41:39.584029Z","time spent":"411.141901ms","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"info","ts":"2026-04-10T13:41:40.143417Z","caller":"traceutil/trace.go:171","msg":"trace[761102214] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"144.490987ms","start":"2026-04-10T13:41:39.998898Z","end":"2026-04-10T13:41:40.143389Z","steps":["trace[761102214] 'process raft request' (duration: 144.404385ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:40.143665Z","caller":"traceutil/trace.go:171","msg":"trace[797500219] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"145.010443ms","start":"2026-04-10T13:41:39.998619Z","end":"2026-04-10T13:41:40.143629Z","steps":["trace[797500219] 'process raft request' (duration: 139.884684ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:40.144208Z","caller":"traceutil/trace.go:171","msg":"trace[1754549067] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:400; }","duration":"118.837921ms","start":"2026-04-10T13:41:40.02535Z","end":"2026-04-10T13:41:40.144188Z","steps":["trace[1754549067] 'read index received' (duration: 113.248128ms)","trace[1754549067] 'applied index is now lower than readState.Index' (duration: 5.588953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:41:40.144326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.958565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:41:40.144355Z","caller":"traceutil/trace.go:171","msg":"trace[715586773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:387; }","duration":"119.027017ms","start":"2026-04-10T13:41:40.02532Z","end":"2026-04-10T13:41:40.144347Z","steps":["trace[715586773] 'agreement among raft nodes before linearized reading' (duration: 118.950995ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:40.144522Z","caller":"traceutil/trace.go:171","msg":"trace[1145026204] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"125.703774ms","start":"2026-04-10T13:41:40.018803Z","end":"2026-04-10T13:41:40.144506Z","steps":["trace[1145026204] 'process raft request' (duration: 125.212999ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:41:58.238102Z","caller":"traceutil/trace.go:171","msg":"trace[247399410] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"151.238261ms","start":"2026-04-10T13:41:58.086844Z","end":"2026-04-10T13:41:58.238082Z","steps":["trace[247399410] 'process raft request' (duration: 150.743717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:02.7542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409637374753873,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-10T13:42:02.934099Z","caller":"traceutil/trace.go:171","msg":"trace[2129732580] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"823.157079ms","start":"2026-04-10T13:42:02.110908Z","end":"2026-04-10T13:42:02.934065Z","steps":["trace[2129732580] 'process raft request' (duration: 822.949404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:02.934272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.110895Z","time spent":"823.293993ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:42:03.047341Z","caller":"traceutil/trace.go:171","msg":"trace[1021477397] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"797.630612ms","start":"2026-04-10T13:42:02.24967Z","end":"2026-04-10T13:42:03.047301Z","steps":["trace[1021477397] 'process raft request' (duration: 797.430256ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.047394Z","caller":"traceutil/trace.go:171","msg":"trace[526831248] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"785.727309ms","start":"2026-04-10T13:42:02.261628Z","end":"2026-04-10T13:42:03.047355Z","steps":["trace[526831248] 'process raft request' (duration: 785.550544ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.047429Z","caller":"traceutil/trace.go:171","msg":"trace[604354574] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"785.619146ms","start":"2026-04-10T13:42:02.261774Z","end":"2026-04-10T13:42:03.047394Z","steps":["trace[604354574] 'process raft request' (duration: 785.479902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:03.047568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.249655Z","time spent":"797.797277ms","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:42:03.047535Z","caller":"traceutil/trace.go:171","msg":"trace[20081153] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"785.812301ms","start":"2026-04-10T13:42:02.261662Z","end":"2026-04-10T13:42:03.047474Z","steps":["trace[20081153] 'process raft request' (duration: 785.549874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:03.047573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.261767Z","time spent":"785.73207ms","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:42:03.047656Z","caller":"traceutil/trace.go:171","msg":"trace[426263790] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"797.895909ms","start":"2026-04-10T13:42:02.249726Z","end":"2026-04-10T13:42:03.047622Z","steps":["trace[426263790] 'process raft request' (duration: 797.416737ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.047736Z","caller":"traceutil/trace.go:171","msg":"trace[1217990348] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"785.556135ms","start":"2026-04-10T13:42:02.262153Z","end":"2026-04-10T13:42:03.047709Z","steps":["trace[1217990348] 'process raft request' (duration: 785.182296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:03.047768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.249722Z","time spent":"798.003112ms","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:42:03.047792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.261632Z","time spent":"786.025507ms","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:42:03.04783Z","caller":"traceutil/trace.go:171","msg":"trace[580058702] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"785.969086ms","start":"2026-04-10T13:42:02.261846Z","end":"2026-04-10T13:42:03.047815Z","steps":["trace[580058702] 'process raft request' (duration: 785.442082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:03.047854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.262144Z","time spent":"785.653348ms","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:42:03.048045Z","caller":"traceutil/trace.go:171","msg":"trace[458004628] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"779.981829ms","start":"2026-04-10T13:42:02.268049Z","end":"2026-04-10T13:42:03.048031Z","steps":["trace[458004628] 'process raft request' (duration: 779.326002ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.04808Z","caller":"traceutil/trace.go:171","msg":"trace[2041851638] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"761.524534ms","start":"2026-04-10T13:42:02.286528Z","end":"2026-04-10T13:42:03.048053Z","steps":["trace[2041851638] 'process raft request' (duration: 760.896098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:03.048118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.268029Z","time spent":"780.05947ms","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:42:03.048137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.286475Z","time spent":"761.635997ms","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:42:03.047574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.261606Z","time spent":"785.876423ms","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:42:03.048363Z","caller":"traceutil/trace.go:171","msg":"trace[1169850886] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"721.262988ms","start":"2026-04-10T13:42:02.32708Z","end":"2026-04-10T13:42:03.048343Z","steps":["trace[1169850886] 'process raft request' (duration: 720.501968ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.048389Z","caller":"traceutil/trace.go:171","msg":"trace[1691438030] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"601.497655ms","start":"2026-04-10T13:42:02.446874Z","end":"2026-04-10T13:42:03.048372Z","steps":["trace[1691438030] 'process raft request' (duration: 601.168246ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.048379Z","caller":"traceutil/trace.go:171","msg":"trace[1645536679] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:518; }","duration":"794.569031ms","start":"2026-04-10T13:42:02.253793Z","end":"2026-04-10T13:42:03.048362Z","steps":["trace[1645536679] 'read index received' (duration: 680.232159ms)","trace[1645536679] 'applied index is now lower than readState.Index' (duration: 114.335852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:42:03.048469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.446835Z","time spent":"601.599357ms","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:42:03.048495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.327046Z","time spent":"721.368321ms","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:42:03.047952Z","caller":"traceutil/trace.go:171","msg":"trace[1540346135] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"802.475889ms","start":"2026-04-10T13:42:02.245294Z","end":"2026-04-10T13:42:03.04777Z","steps":["trace[1540346135] 'process raft request' (duration: 801.648607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:03.048774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.856852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:42:03.048895Z","caller":"traceutil/trace.go:171","msg":"trace[1160172105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"262.033747ms","start":"2026-04-10T13:42:02.786841Z","end":"2026-04-10T13:42:03.048875Z","steps":["trace[1160172105] 'agreement among raft nodes before linearized reading' (duration: 261.866552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:03.048542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.736276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-10T13:42:03.049009Z","caller":"traceutil/trace.go:171","msg":"trace[536356522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"795.240629ms","start":"2026-04-10T13:42:02.253754Z","end":"2026-04-10T13:42:03.048994Z","steps":["trace[536356522] 'agreement among raft nodes before linearized reading' (duration: 794.690395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:03.048805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.28071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-10T13:42:03.049061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.253742Z","time spent":"795.30688ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T13:42:03.048675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.785759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-10T13:42:03.04911Z","caller":"traceutil/trace.go:171","msg":"trace[1502309752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:510; }","duration":"768.272272ms","start":"2026-04-10T13:42:02.280823Z","end":"2026-04-10T13:42:03.049095Z","steps":["trace[1502309752] 'agreement among raft nodes before linearized reading' (duration: 767.804399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:03.049173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.280798Z","time spent":"768.359214ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T13:42:03.049059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.245263Z","time spent":"803.537047ms","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:42:03.049609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.261838Z","time spent":"786.033548ms","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:42:03.053486Z","caller":"traceutil/trace.go:171","msg":"trace[1674994867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"763.579147ms","start":"2026-04-10T13:42:02.285477Z","end":"2026-04-10T13:42:03.049056Z","steps":["trace[1674994867] 'agreement among raft nodes before linearized reading' (duration: 763.244479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:03.053544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:02.285459Z","time spent":"768.067055ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-10T13:42:03.275681Z","caller":"traceutil/trace.go:171","msg":"trace[20612667] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"208.987997ms","start":"2026-04-10T13:42:03.066662Z","end":"2026-04-10T13:42:03.27565Z","steps":["trace[20612667] 'process raft request' (duration: 208.874944ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.276168Z","caller":"traceutil/trace.go:171","msg":"trace[655490234] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"217.407346ms","start":"2026-04-10T13:42:03.058705Z","end":"2026-04-10T13:42:03.276112Z","steps":["trace[655490234] 'process raft request' (duration: 216.587685ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.276575Z","caller":"traceutil/trace.go:171","msg":"trace[1164423324] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"209.597133ms","start":"2026-04-10T13:42:03.066962Z","end":"2026-04-10T13:42:03.276559Z","steps":["trace[1164423324] 'process raft request' (duration: 209.244514ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.276729Z","caller":"traceutil/trace.go:171","msg":"trace[1248399691] transaction","detail":"{read_only:false; number_of_response:1; response_revision:519; }","duration":"209.418238ms","start":"2026-04-10T13:42:03.067298Z","end":"2026-04-10T13:42:03.276716Z","steps":["trace[1248399691] 'process raft request' (duration: 209.056239ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.276695Z","caller":"traceutil/trace.go:171","msg":"trace[1266880537] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"205.074005ms","start":"2026-04-10T13:42:03.071584Z","end":"2026-04-10T13:42:03.276658Z","steps":["trace[1266880537] 'process raft request' (duration: 204.959322ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.276769Z","caller":"traceutil/trace.go:171","msg":"trace[737269055] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"210.016354ms","start":"2026-04-10T13:42:03.066742Z","end":"2026-04-10T13:42:03.276759Z","steps":["trace[737269055] 'process raft request' (duration: 208.834923ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.277003Z","caller":"traceutil/trace.go:171","msg":"trace[2127025875] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"205.677971ms","start":"2026-04-10T13:42:03.071312Z","end":"2026-04-10T13:42:03.27699Z","steps":["trace[2127025875] 'process raft request' (duration: 205.180888ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.277056Z","caller":"traceutil/trace.go:171","msg":"trace[877861670] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"208.906455ms","start":"2026-04-10T13:42:03.06814Z","end":"2026-04-10T13:42:03.277047Z","steps":["trace[877861670] 'process raft request' (duration: 208.249967ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.277046Z","caller":"traceutil/trace.go:171","msg":"trace[1502977499] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"210.229039ms","start":"2026-04-10T13:42:03.06681Z","end":"2026-04-10T13:42:03.277039Z","steps":["trace[1502977499] 'process raft request' (duration: 209.363327ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.277009Z","caller":"traceutil/trace.go:171","msg":"trace[1785523376] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"210.216559ms","start":"2026-04-10T13:42:03.066777Z","end":"2026-04-10T13:42:03.276994Z","steps":["trace[1785523376] 'process raft request' (duration: 209.305805ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.277191Z","caller":"traceutil/trace.go:171","msg":"trace[804416903] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"209.960142ms","start":"2026-04-10T13:42:03.06722Z","end":"2026-04-10T13:42:03.27718Z","steps":["trace[804416903] 'process raft request' (duration: 209.037718ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.277629Z","caller":"traceutil/trace.go:171","msg":"trace[830600961] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"210.304941ms","start":"2026-04-10T13:42:03.067299Z","end":"2026-04-10T13:42:03.277604Z","steps":["trace[830600961] 'process raft request' (duration: 209.002927ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.277837Z","caller":"traceutil/trace.go:171","msg":"trace[1250373869] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"206.546863ms","start":"2026-04-10T13:42:03.071221Z","end":"2026-04-10T13:42:03.277768Z","steps":["trace[1250373869] 'process raft request' (duration: 205.212689ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:03.300991Z","caller":"traceutil/trace.go:171","msg":"trace[714780809] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"194.505509ms","start":"2026-04-10T13:42:03.106467Z","end":"2026-04-10T13:42:03.300973Z","steps":["trace[714780809] 'process raft request' (duration: 194.407657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:09.287764Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409637374754028,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T13:42:09.699328Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.05008201s","expected-duration":"1s"} {"level":"info","ts":"2026-04-10T13:42:09.700755Z","caller":"traceutil/trace.go:171","msg":"trace[843536788] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"913.813315ms","start":"2026-04-10T13:42:08.786906Z","end":"2026-04-10T13:42:09.700719Z","steps":["trace[843536788] 'read index received' (duration: 912.555603ms)","trace[843536788] 'applied index is now lower than readState.Index' (duration: 1.256571ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:42:09.700891Z","caller":"traceutil/trace.go:171","msg":"trace[972074073] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.054516691s","start":"2026-04-10T13:42:08.646317Z","end":"2026-04-10T13:42:09.700833Z","steps":["trace[972074073] 'process raft request' (duration: 1.053597838s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:09.700974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.04147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T13:42:09.701052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:08.64629Z","time spent":"1.054666115s","remote":"127.0.0.1:45568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:42:09.701075Z","caller":"traceutil/trace.go:171","msg":"trace[1357423067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"914.190444ms","start":"2026-04-10T13:42:08.786858Z","end":"2026-04-10T13:42:09.701048Z","steps":["trace[1357423067] 'agreement among raft nodes before linearized reading' (duration: 914.017929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:09.701072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.317186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-10T13:42:09.701117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:08.786837Z","time spent":"914.272376ms","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T13:42:09.701116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.791434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-10T13:42:09.701184Z","caller":"traceutil/trace.go:171","msg":"trace[1254791165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"401.923787ms","start":"2026-04-10T13:42:09.29924Z","end":"2026-04-10T13:42:09.701164Z","steps":["trace[1254791165] 'agreement among raft nodes before linearized reading' (duration: 401.776933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:09.701204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.607724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-10T13:42:09.701239Z","caller":"traceutil/trace.go:171","msg":"trace[1428411160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"590.661844ms","start":"2026-04-10T13:42:09.110569Z","end":"2026-04-10T13:42:09.70123Z","steps":["trace[1428411160] 'agreement among raft nodes before linearized reading' (duration: 590.569142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:09.701273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:09.29922Z","time spent":"402.04197ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T13:42:09.701314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:09.110552Z","time spent":"590.752377ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T13:42:09.701339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.705954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-10T13:42:09.701207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.327877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-10T13:42:09.701405Z","caller":"traceutil/trace.go:171","msg":"trace[1446933485] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:598; }","duration":"116.788285ms","start":"2026-04-10T13:42:09.584591Z","end":"2026-04-10T13:42:09.701379Z","steps":["trace[1446933485] 'agreement among raft nodes before linearized reading' (duration: 116.708733ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:09.701119Z","caller":"traceutil/trace.go:171","msg":"trace[55970093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"743.418908ms","start":"2026-04-10T13:42:08.957686Z","end":"2026-04-10T13:42:09.701105Z","steps":["trace[55970093] 'agreement among raft nodes before linearized reading' (duration: 743.216623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:09.701449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:08.957671Z","time spent":"743.771468ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-10T13:42:09.701443Z","caller":"traceutil/trace.go:171","msg":"trace[1866673733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"396.621054ms","start":"2026-04-10T13:42:09.304808Z","end":"2026-04-10T13:42:09.701429Z","steps":["trace[1866673733] 'agreement among raft nodes before linearized reading' (duration: 396.129832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:09.701519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:09.304792Z","time spent":"396.707796ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-10T13:42:10.559044Z","caller":"traceutil/trace.go:171","msg":"trace[1139442447] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"350.967504ms","start":"2026-04-10T13:42:10.208033Z","end":"2026-04-10T13:42:10.559001Z","steps":["trace[1139442447] 'process raft request' (duration: 349.725754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:10.559194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:10.207996Z","time spent":"351.120018ms","remote":"127.0.0.1:45568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:42:11.287655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409637374754052,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T13:42:11.788145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409637374754052,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T13:42:11.830238Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.196915339s","expected-duration":"1s"} {"level":"info","ts":"2026-04-10T13:42:11.830798Z","caller":"traceutil/trace.go:171","msg":"trace[557537703] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.197621306s","start":"2026-04-10T13:42:10.633153Z","end":"2026-04-10T13:42:11.830774Z","steps":["trace[557537703] 'process raft request' (duration: 1.19738232s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:11.831003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:10.633129Z","time spent":"1.19773803s","remote":"127.0.0.1:46028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:42:12.787295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000149477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-10T13:42:12.787386Z","caller":"traceutil/trace.go:171","msg":"trace[289908523] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000290981s","start":"2026-04-10T13:42:10.787075Z","end":"2026-04-10T13:42:12.787366Z","steps":["trace[289908523] 'agreement among raft nodes before linearized reading' (duration: 2.000181668s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:12.787446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:10.787054Z","time spent":"2.000380903s","remote":"127.0.0.1:45484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/10 13:42:12 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-10T13:42:14.127384Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.336542355s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-10T13:42:14.797779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000299184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-10T13:42:14.797881Z","caller":"traceutil/trace.go:171","msg":"trace[1687307524] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000455448s","start":"2026-04-10T13:42:12.797407Z","end":"2026-04-10T13:42:14.797862Z","steps":["trace[1687307524] 'agreement among raft nodes before linearized reading' (duration: 2.000328815s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:14.797933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:12.797387Z","time spent":"2.00053453s","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T13:42:14.990921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.148070137s","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-04-10T13:42:14.991045Z","caller":"traceutil/trace.go:171","msg":"trace[1884377917] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:610; }","duration":"3.14820433s","start":"2026-04-10T13:42:11.842824Z","end":"2026-04-10T13:42:14.991028Z","steps":["trace[1884377917] 'range keys from in-memory index tree' (duration: 3.148057947s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:14.991384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.128784s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:42:14.991639Z","caller":"traceutil/trace.go:171","msg":"trace[769419713] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"2.200850824s","start":"2026-04-10T13:42:12.790765Z","end":"2026-04-10T13:42:14.991616Z","steps":["trace[769419713] 'process raft request' (duration: 2.200667869s)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:14.991707Z","caller":"traceutil/trace.go:171","msg":"trace[1874311550] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"4.204568369s","start":"2026-04-10T13:42:10.787125Z","end":"2026-04-10T13:42:14.991693Z","steps":["trace[1874311550] 'read index received' (duration: 1.043421799s)","trace[1874311550] 'applied index is now lower than readState.Index' (duration: 3.161144349s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:42:14.991888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:12.790746Z","time spent":"2.200999377s","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:42:14.991623Z","caller":"traceutil/trace.go:171","msg":"trace[921445470] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"3.898732041s","start":"2026-04-10T13:42:11.092781Z","end":"2026-04-10T13:42:14.991513Z","steps":["trace[921445470] 'process raft request' (duration: 769.768967ms)","trace[921445470] 'compare' (duration: 3.128642416s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:42:14.992367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:11.092756Z","time spent":"3.899440878s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:42:14.992519Z","caller":"traceutil/trace.go:171","msg":"trace[660058392] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"408.669819ms","start":"2026-04-10T13:42:14.583821Z","end":"2026-04-10T13:42:14.99249Z","steps":["trace[660058392] 'process raft request' (duration: 407.647594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:14.992643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:14.583796Z","time spent":"408.784992ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:42:15.020684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.974905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T13:42:15.020693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.292473472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-10T13:42:15.020736Z","caller":"traceutil/trace.go:171","msg":"trace[2034093358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"222.052117ms","start":"2026-04-10T13:42:14.798673Z","end":"2026-04-10T13:42:15.020725Z","steps":["trace[2034093358] 'agreement among raft nodes before linearized reading' (duration: 221.980955ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:15.020756Z","caller":"traceutil/trace.go:171","msg":"trace[665806204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"3.292582484s","start":"2026-04-10T13:42:11.728158Z","end":"2026-04-10T13:42:15.02074Z","steps":["trace[665806204] 'agreement among raft nodes before linearized reading' (duration: 3.29240259s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:15.020771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149309644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T13:42:15.02083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:11.728139Z","time spent":"3.292643816s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-10T13:42:15.020817Z","caller":"traceutil/trace.go:171","msg":"trace[1694148057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"1.149388956s","start":"2026-04-10T13:42:13.87141Z","end":"2026-04-10T13:42:15.020799Z","steps":["trace[1694148057] 'agreement among raft nodes before linearized reading' (duration: 1.149339074s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:15.020812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.285021638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-10T13:42:15.020868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:13.871387Z","time spent":"1.149473408s","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-10T13:42:15.020884Z","caller":"traceutil/trace.go:171","msg":"trace[1517397187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"3.285106739s","start":"2026-04-10T13:42:11.735759Z","end":"2026-04-10T13:42:15.020866Z","steps":["trace[1517397187] 'agreement among raft nodes before linearized reading' (duration: 3.284910635s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:15.020928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:11.735751Z","time spent":"3.285165341s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T13:42:15.02069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.177390046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T13:42:15.021045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.279507891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-10T13:42:15.021056Z","caller":"traceutil/trace.go:171","msg":"trace[1452210017] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:613; }","duration":"3.177802306s","start":"2026-04-10T13:42:11.843236Z","end":"2026-04-10T13:42:15.021038Z","steps":["trace[1452210017] 'agreement among raft nodes before linearized reading' (duration: 3.177363596s)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:15.021069Z","caller":"traceutil/trace.go:171","msg":"trace[1640162138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"3.279573373s","start":"2026-04-10T13:42:11.74149Z","end":"2026-04-10T13:42:15.021063Z","steps":["trace[1640162138] 'agreement among raft nodes before linearized reading' (duration: 3.279504691s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:15.021091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:11.741468Z","time spent":"3.279618684s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T13:42:15.021098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:11.843218Z","time spent":"3.177868088s","remote":"127.0.0.1:45734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-10T13:42:15.021076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.287774425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-10T13:42:15.021164Z","caller":"traceutil/trace.go:171","msg":"trace[297092448] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"3.287923919s","start":"2026-04-10T13:42:11.733218Z","end":"2026-04-10T13:42:15.021142Z","steps":["trace[297092448] 'agreement among raft nodes before linearized reading' (duration: 3.287645232s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:15.02136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:11.733212Z","time spent":"3.288129195s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T13:42:16.937572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.016039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:42:16.937645Z","caller":"traceutil/trace.go:171","msg":"trace[1155501799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"153.123462ms","start":"2026-04-10T13:42:16.784505Z","end":"2026-04-10T13:42:16.937628Z","steps":["trace[1155501799] 'range keys from in-memory index tree' (duration: 152.931468ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:21.307465Z","caller":"traceutil/trace.go:171","msg":"trace[1371886875] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:724; }","duration":"238.047663ms","start":"2026-04-10T13:42:21.06939Z","end":"2026-04-10T13:42:21.307438Z","steps":["trace[1371886875] 'read index received' (duration: 237.716526ms)","trace[1371886875] 'applied index is now lower than readState.Index' (duration: 330.127µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:42:21.307627Z","caller":"traceutil/trace.go:171","msg":"trace[1055524784] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"264.242563ms","start":"2026-04-10T13:42:21.043349Z","end":"2026-04-10T13:42:21.307592Z","steps":["trace[1055524784] 'process raft request' (duration: 263.866464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:21.307835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.432042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-10T13:42:21.307843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.600452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-10T13:42:21.307888Z","caller":"traceutil/trace.go:171","msg":"trace[690093264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:699; }","duration":"238.518774ms","start":"2026-04-10T13:42:21.069355Z","end":"2026-04-10T13:42:21.307873Z","steps":["trace[690093264] 'agreement among raft nodes before linearized reading' (duration: 238.270769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:21.307875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.857189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-04-10T13:42:21.307901Z","caller":"traceutil/trace.go:171","msg":"trace[292657254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:699; }","duration":"231.670015ms","start":"2026-04-10T13:42:21.076213Z","end":"2026-04-10T13:42:21.307883Z","steps":["trace[292657254] 'agreement among raft nodes before linearized reading' (duration: 231.48087ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:21.307936Z","caller":"traceutil/trace.go:171","msg":"trace[339355288] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:699; }","duration":"164.973492ms","start":"2026-04-10T13:42:21.142947Z","end":"2026-04-10T13:42:21.30792Z","steps":["trace[339355288] 'agreement among raft nodes before linearized reading' (duration: 164.729596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:21.308022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.452925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-10T13:42:21.308041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.049947ms","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-04-10T13:42:21.308062Z","caller":"traceutil/trace.go:171","msg":"trace[1160682420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:699; }","duration":"236.518748ms","start":"2026-04-10T13:42:21.071531Z","end":"2026-04-10T13:42:21.308049Z","steps":["trace[1160682420] 'agreement among raft nodes before linearized reading' (duration: 236.386824ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:21.30807Z","caller":"traceutil/trace.go:171","msg":"trace[546537102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:699; }","duration":"157.087148ms","start":"2026-04-10T13:42:21.150974Z","end":"2026-04-10T13:42:21.308061Z","steps":["trace[546537102] 'agreement among raft nodes before linearized reading' (duration: 157.007606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:21.308222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.011002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-10T13:42:21.308261Z","caller":"traceutil/trace.go:171","msg":"trace[1189441901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:699; }","duration":"238.076613ms","start":"2026-04-10T13:42:21.070175Z","end":"2026-04-10T13:42:21.308252Z","steps":["trace[1189441901] 'agreement among raft nodes before linearized reading' (duration: 237.484649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:34.833234Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409637374754481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T13:42:35.333356Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409637374754481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T13:42:35.833988Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409637374754481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T13:42:35.93864Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.953363848s","expected-duration":"1s"} {"level":"info","ts":"2026-04-10T13:42:35.939179Z","caller":"traceutil/trace.go:171","msg":"trace[1898149699] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"1.954937292s","start":"2026-04-10T13:42:33.98422Z","end":"2026-04-10T13:42:35.939157Z","steps":["trace[1898149699] 'process raft request' (duration: 1.954773568s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:35.939345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:33.9842Z","time spent":"1.955057764s","remote":"127.0.0.1:45668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:42:35.945663Z","caller":"traceutil/trace.go:171","msg":"trace[707195288] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:818; }","duration":"1.61332341s","start":"2026-04-10T13:42:34.332319Z","end":"2026-04-10T13:42:35.945642Z","steps":["trace[707195288] 'read index received' (duration: 1.606740618s)","trace[707195288] 'applied index is now lower than readState.Index' (duration: 6.581612ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:42:35.945821Z","caller":"traceutil/trace.go:171","msg":"trace[335676025] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"687.000355ms","start":"2026-04-10T13:42:35.258799Z","end":"2026-04-10T13:42:35.945799Z","steps":["trace[335676025] 'process raft request' (duration: 686.711279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:35.946424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.752998ms","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-04-10T13:42:35.94653Z","caller":"traceutil/trace.go:171","msg":"trace[342017637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:791; }","duration":"486.935541ms","start":"2026-04-10T13:42:35.459579Z","end":"2026-04-10T13:42:35.946515Z","steps":["trace[342017637] 'agreement among raft nodes before linearized reading' (duration: 486.667805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:35.946571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:35.459562Z","time spent":"487.000433ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T13:42:35.946003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613594327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T13:42:35.946711Z","caller":"traceutil/trace.go:171","msg":"trace[1674808520] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:791; }","duration":"1.614390083s","start":"2026-04-10T13:42:34.332274Z","end":"2026-04-10T13:42:35.946664Z","steps":["trace[1674808520] 'agreement among raft nodes before linearized reading' (duration: 1.613601786s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:35.946789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:34.332251Z","time spent":"1.614513677s","remote":"127.0.0.1:45860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-10T13:42:35.946081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158523735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:42:35.947118Z","caller":"traceutil/trace.go:171","msg":"trace[1120001085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"1.159586598s","start":"2026-04-10T13:42:34.787515Z","end":"2026-04-10T13:42:35.947102Z","steps":["trace[1120001085] 'agreement among raft nodes before linearized reading' (duration: 1.158541325s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:35.947161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:34.787493Z","time spent":"1.159656929s","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-10T13:42:35.946086Z","caller":"traceutil/trace.go:171","msg":"trace[1130517231] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"225.969032ms","start":"2026-04-10T13:42:35.720079Z","end":"2026-04-10T13:42:35.946048Z","steps":["trace[1130517231] 'process raft request' (duration: 225.525063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:35.946183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.938436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-10T13:42:35.947457Z","caller":"traceutil/trace.go:171","msg":"trace[1103601393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"441.222083ms","start":"2026-04-10T13:42:35.506223Z","end":"2026-04-10T13:42:35.947445Z","steps":["trace[1103601393] 'agreement among raft nodes before linearized reading' (duration: 439.875264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:35.947495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:35.506216Z","time spent":"441.269015ms","remote":"127.0.0.1:45770","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-04-10T13:42:35.946248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.070384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-10T13:42:35.947586Z","caller":"traceutil/trace.go:171","msg":"trace[1629964266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:791; }","duration":"494.443254ms","start":"2026-04-10T13:42:35.453125Z","end":"2026-04-10T13:42:35.947568Z","steps":["trace[1629964266] 'agreement among raft nodes before linearized reading' (duration: 493.074204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:35.947677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:35.453099Z","time spent":"494.558766ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T13:42:35.94629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146921152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T13:42:35.947967Z","caller":"traceutil/trace.go:171","msg":"trace[1717046087] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:791; }","duration":"1.148517057s","start":"2026-04-10T13:42:34.799339Z","end":"2026-04-10T13:42:35.947856Z","steps":["trace[1717046087] 'agreement among raft nodes before linearized reading' (duration: 1.146925483s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:35.947922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:35.258773Z","time spent":"687.73476ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:42:35.948238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:34.799327Z","time spent":"1.14867213s","remote":"127.0.0.1:45648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-10T13:42:35.946304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.363494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-10T13:42:35.948455Z","caller":"traceutil/trace.go:171","msg":"trace[952407324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:791; }","duration":"491.562982ms","start":"2026-04-10T13:42:35.456876Z","end":"2026-04-10T13:42:35.948439Z","steps":["trace[952407324] 'agreement among raft nodes before linearized reading' (duration: 489.272692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:35.948526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:35.456852Z","time spent":"491.657643ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-10T13:42:35.946305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.411996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-10T13:42:35.948603Z","caller":"traceutil/trace.go:171","msg":"trace[1582104095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:791; }","duration":"442.748257ms","start":"2026-04-10T13:42:35.505841Z","end":"2026-04-10T13:42:35.948589Z","steps":["trace[1582104095] 'agreement among raft nodes before linearized reading' (duration: 440.326765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:35.948664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:35.505826Z","time spent":"442.822128ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-10T13:42:43.0224Z","caller":"traceutil/trace.go:171","msg":"trace[48024707] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"167.08043ms","start":"2026-04-10T13:42:42.855286Z","end":"2026-04-10T13:42:43.022367Z","steps":["trace[48024707] 'process raft request' (duration: 166.884996ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:43.091521Z","caller":"traceutil/trace.go:171","msg":"trace[990878628] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"235.892511ms","start":"2026-04-10T13:42:42.855601Z","end":"2026-04-10T13:42:43.091493Z","steps":["trace[990878628] 'process raft request' (duration: 235.762368ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:44.146448Z","caller":"traceutil/trace.go:171","msg":"trace[87238138] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"120.311722ms","start":"2026-04-10T13:42:44.026114Z","end":"2026-04-10T13:42:44.146425Z","steps":["trace[87238138] 'process raft request' (duration: 120.258281ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:44.146817Z","caller":"traceutil/trace.go:171","msg":"trace[519482279] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"150.999598ms","start":"2026-04-10T13:42:43.995802Z","end":"2026-04-10T13:42:44.146802Z","steps":["trace[519482279] 'process raft request' (duration: 118.807511ms)","trace[519482279] 'compare' (duration: 31.439371ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:42:44.146969Z","caller":"traceutil/trace.go:171","msg":"trace[37437791] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:902; }","duration":"124.54501ms","start":"2026-04-10T13:42:44.022395Z","end":"2026-04-10T13:42:44.14694Z","steps":["trace[37437791] 'read index received' (duration: 92.185449ms)","trace[37437791] 'applied index is now lower than readState.Index' (duration: 32.356291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:42:44.147101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.423896ms","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-04-10T13:42:44.147146Z","caller":"traceutil/trace.go:171","msg":"trace[622845820] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:872; }","duration":"151.491999ms","start":"2026-04-10T13:42:43.995643Z","end":"2026-04-10T13:42:44.147135Z","steps":["trace[622845820] 'agreement among raft nodes before linearized reading' (duration: 151.355746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:44.147367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.558088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-10T13:42:44.147399Z","caller":"traceutil/trace.go:171","msg":"trace[923439835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:872; }","duration":"150.6168ms","start":"2026-04-10T13:42:43.996774Z","end":"2026-04-10T13:42:44.147391Z","steps":["trace[923439835] 'agreement among raft nodes before linearized reading' (duration: 150.540068ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:44.326679Z","caller":"traceutil/trace.go:171","msg":"trace[739158629] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"173.024533ms","start":"2026-04-10T13:42:44.153618Z","end":"2026-04-10T13:42:44.326643Z","steps":["trace[739158629] 'process raft request' (duration: 140.310985ms)","trace[739158629] 'compare' (duration: 32.584935ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:42:44.980147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.566936ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-10T13:42:45.71117Z","caller":"traceutil/trace.go:171","msg":"trace[447211807] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"267.501955ms","start":"2026-04-10T13:42:45.443653Z","end":"2026-04-10T13:42:45.711155Z","steps":["trace[447211807] 'process raft request' (duration: 267.399163ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.282981Z","caller":"traceutil/trace.go:171","msg":"trace[1448004038] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"170.850727ms","start":"2026-04-10T13:42:46.1121Z","end":"2026-04-10T13:42:46.282951Z","steps":["trace[1448004038] 'process raft request' (duration: 170.640102ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.283031Z","caller":"traceutil/trace.go:171","msg":"trace[981240580] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"170.638463ms","start":"2026-04-10T13:42:46.112364Z","end":"2026-04-10T13:42:46.283003Z","steps":["trace[981240580] 'process raft request' (duration: 170.51901ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.283006Z","caller":"traceutil/trace.go:171","msg":"trace[1580210173] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"167.569189ms","start":"2026-04-10T13:42:46.115416Z","end":"2026-04-10T13:42:46.282985Z","steps":["trace[1580210173] 'process raft request' (duration: 167.522368ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.384758Z","caller":"traceutil/trace.go:171","msg":"trace[320220686] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:920; }","duration":"227.250495ms","start":"2026-04-10T13:42:46.157485Z","end":"2026-04-10T13:42:46.384735Z","steps":["trace[320220686] 'read index received' (duration: 125.355483ms)","trace[320220686] 'applied index is now lower than readState.Index' (duration: 101.894102ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:42:46.384865Z","caller":"traceutil/trace.go:171","msg":"trace[1571517180] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"250.026522ms","start":"2026-04-10T13:42:46.134827Z","end":"2026-04-10T13:42:46.384853Z","steps":["trace[1571517180] 'process raft request' (duration: 206.750324ms)","trace[1571517180] 'compare' (duration: 42.975662ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:42:46.385105Z","caller":"traceutil/trace.go:171","msg":"trace[1197493053] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"223.61024ms","start":"2026-04-10T13:42:46.161469Z","end":"2026-04-10T13:42:46.385079Z","steps":["trace[1197493053] 'process raft request' (duration: 223.214062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:46.385271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.726725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T13:42:46.385356Z","caller":"traceutil/trace.go:171","msg":"trace[598639309] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:892; }","duration":"227.895527ms","start":"2026-04-10T13:42:46.15744Z","end":"2026-04-10T13:42:46.385335Z","steps":["trace[598639309] 'agreement among raft nodes before linearized reading' (duration: 227.690103ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.441288Z","caller":"traceutil/trace.go:171","msg":"trace[71249327] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"149.807655ms","start":"2026-04-10T13:42:46.291461Z","end":"2026-04-10T13:42:46.441269Z","steps":["trace[71249327] 'process raft request' (duration: 149.741254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:46.441574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.000798ms","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-04-10T13:42:46.441643Z","caller":"traceutil/trace.go:171","msg":"trace[1928861983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:894; }","duration":"111.11341ms","start":"2026-04-10T13:42:46.330513Z","end":"2026-04-10T13:42:46.441627Z","steps":["trace[1928861983] 'agreement among raft nodes before linearized reading' (duration: 110.950867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:46.441734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.099448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-10T13:42:46.441777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.198143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-10T13:42:46.442011Z","caller":"traceutil/trace.go:171","msg":"trace[1279894429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:894; }","duration":"284.450688ms","start":"2026-04-10T13:42:46.157541Z","end":"2026-04-10T13:42:46.441992Z","steps":["trace[1279894429] 'agreement among raft nodes before linearized reading' (duration: 284.05891ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.441854Z","caller":"traceutil/trace.go:171","msg":"trace[2023710732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"240.227221ms","start":"2026-04-10T13:42:46.201551Z","end":"2026-04-10T13:42:46.441779Z","steps":["trace[2023710732] 'agreement among raft nodes before linearized reading' (duration: 239.993666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:46.441933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.375947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-10T13:42:46.442319Z","caller":"traceutil/trace.go:171","msg":"trace[539199062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:894; }","duration":"284.790686ms","start":"2026-04-10T13:42:46.157521Z","end":"2026-04-10T13:42:46.442312Z","steps":["trace[539199062] 'agreement among raft nodes before linearized reading' (duration: 283.877107ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.442558Z","caller":"traceutil/trace.go:171","msg":"trace[1813242350] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"154.988691ms","start":"2026-04-10T13:42:46.287535Z","end":"2026-04-10T13:42:46.442523Z","steps":["trace[1813242350] 'process raft request' (duration: 153.562002ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.560478Z","caller":"traceutil/trace.go:171","msg":"trace[1987202471] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:927; }","duration":"115.966441ms","start":"2026-04-10T13:42:46.444494Z","end":"2026-04-10T13:42:46.56046Z","steps":["trace[1987202471] 'read index received' (duration: 115.580633ms)","trace[1987202471] 'applied index is now lower than readState.Index' (duration: 385.278µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:42:46.560653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.123034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-8p4vj\" ","response":"range_response_count:1 size:4869"} {"level":"info","ts":"2026-04-10T13:42:46.560693Z","caller":"traceutil/trace.go:171","msg":"trace[1605096438] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-8p4vj; range_end:; response_count:1; response_revision:897; }","duration":"116.206755ms","start":"2026-04-10T13:42:46.444474Z","end":"2026-04-10T13:42:46.560681Z","steps":["trace[1605096438] 'agreement among raft nodes before linearized reading' (duration: 116.053022ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.560689Z","caller":"traceutil/trace.go:171","msg":"trace[888018630] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"170.369977ms","start":"2026-04-10T13:42:46.39029Z","end":"2026-04-10T13:42:46.56066Z","steps":["trace[888018630] 'process raft request' (duration: 169.771685ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.560841Z","caller":"traceutil/trace.go:171","msg":"trace[1581447911] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"168.07948ms","start":"2026-04-10T13:42:46.392734Z","end":"2026-04-10T13:42:46.560814Z","steps":["trace[1581447911] 'process raft request' (duration: 167.695012ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.561053Z","caller":"traceutil/trace.go:171","msg":"trace[18502803] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"170.221354ms","start":"2026-04-10T13:42:46.39082Z","end":"2026-04-10T13:42:46.561042Z","steps":["trace[18502803] 'process raft request' (duration: 169.57173ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.588152Z","caller":"traceutil/trace.go:171","msg":"trace[1428482501] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"143.324042ms","start":"2026-04-10T13:42:46.444797Z","end":"2026-04-10T13:42:46.588121Z","steps":["trace[1428482501] 'process raft request' (duration: 143.127388ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.588323Z","caller":"traceutil/trace.go:171","msg":"trace[489132097] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"139.012623ms","start":"2026-04-10T13:42:46.449289Z","end":"2026-04-10T13:42:46.588302Z","steps":["trace[489132097] 'process raft request' (duration: 138.911161ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.5885Z","caller":"traceutil/trace.go:171","msg":"trace[683076712] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"142.900964ms","start":"2026-04-10T13:42:46.445567Z","end":"2026-04-10T13:42:46.588468Z","steps":["trace[683076712] 'process raft request' (duration: 142.523596ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.588657Z","caller":"traceutil/trace.go:171","msg":"trace[1694811448] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"143.058946ms","start":"2026-04-10T13:42:46.445587Z","end":"2026-04-10T13:42:46.588646Z","steps":["trace[1694811448] 'process raft request' (duration: 142.556906ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.588759Z","caller":"traceutil/trace.go:171","msg":"trace[1047322313] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"142.149047ms","start":"2026-04-10T13:42:46.446602Z","end":"2026-04-10T13:42:46.588751Z","steps":["trace[1047322313] 'process raft request' (duration: 141.570916ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:46.588979Z","caller":"traceutil/trace.go:171","msg":"trace[496297974] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"143.494886ms","start":"2026-04-10T13:42:46.445471Z","end":"2026-04-10T13:42:46.588966Z","steps":["trace[496297974] 'process raft request' (duration: 142.565037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:47.136825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.974555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:42:47.136944Z","caller":"traceutil/trace.go:171","msg":"trace[1483012853] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"146.878483ms","start":"2026-04-10T13:42:46.990043Z","end":"2026-04-10T13:42:47.136922Z","steps":["trace[1483012853] 'process raft request' (duration: 41.731014ms)","trace[1483012853] 'compare' (duration: 104.833673ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:42:47.448684Z","caller":"traceutil/trace.go:171","msg":"trace[1819278745] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"305.090785ms","start":"2026-04-10T13:42:47.143567Z","end":"2026-04-10T13:42:47.448658Z","steps":["trace[1819278745] 'process raft request' (duration: 301.052633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:47.449002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:47.143549Z","time spent":"305.38486ms","remote":"127.0.0.1:45882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1066,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:42:47.451515Z","caller":"traceutil/trace.go:171","msg":"trace[747102261] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"218.366123ms","start":"2026-04-10T13:42:47.233115Z","end":"2026-04-10T13:42:47.451482Z","steps":["trace[747102261] 'process raft request' (duration: 218.188419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:47.847752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.616919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:42:47.84785Z","caller":"traceutil/trace.go:171","msg":"trace[1243697512] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"393.451121ms","start":"2026-04-10T13:42:47.454381Z","end":"2026-04-10T13:42:47.847832Z","steps":["trace[1243697512] 'process raft request' (duration: 287.688249ms)","trace[1243697512] 'compare' (duration: 105.431625ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:42:47.847903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:42:47.454351Z","time spent":"393.528993ms","remote":"127.0.0.1:45982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2872,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T13:42:48.060684Z","caller":"traceutil/trace.go:171","msg":"trace[114546035] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"206.230239ms","start":"2026-04-10T13:42:47.854424Z","end":"2026-04-10T13:42:48.060655Z","steps":["trace[114546035] 'process raft request' (duration: 129.807072ms)","trace[114546035] 'compare' (duration: 76.258534ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:42:48.093184Z","caller":"traceutil/trace.go:171","msg":"trace[1003118189] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"236.035495ms","start":"2026-04-10T13:42:47.857127Z","end":"2026-04-10T13:42:48.093162Z","steps":["trace[1003118189] 'process raft request' (duration: 235.814221ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:48.459988Z","caller":"traceutil/trace.go:171","msg":"trace[581758831] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:967; }","duration":"110.568759ms","start":"2026-04-10T13:42:48.349395Z","end":"2026-04-10T13:42:48.459963Z","steps":["trace[581758831] 'read index received' (duration: 14.777701ms)","trace[581758831] 'applied index is now lower than readState.Index' (duration: 95.789918ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:42:48.460071Z","caller":"traceutil/trace.go:171","msg":"trace[726779084] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"166.887986ms","start":"2026-04-10T13:42:48.293151Z","end":"2026-04-10T13:42:48.460039Z","steps":["trace[726779084] 'process raft request' (duration: 166.616531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:48.460133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.701812ms","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-04-10T13:42:48.46021Z","caller":"traceutil/trace.go:171","msg":"trace[772265272] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:936; }","duration":"110.830615ms","start":"2026-04-10T13:42:48.349359Z","end":"2026-04-10T13:42:48.460189Z","steps":["trace[772265272] 'agreement among raft nodes before linearized reading' (duration: 110.699012ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:52.640691Z","caller":"traceutil/trace.go:171","msg":"trace[1984517986] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"142.224426ms","start":"2026-04-10T13:42:52.498445Z","end":"2026-04-10T13:42:52.640669Z","steps":["trace[1984517986] 'process raft request' (duration: 142.040582ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:53.398355Z","caller":"traceutil/trace.go:171","msg":"trace[1463258592] transaction","detail":"{read_only:false; number_of_response:1; response_revision:971; }","duration":"138.086042ms","start":"2026-04-10T13:42:53.260252Z","end":"2026-04-10T13:42:53.398338Z","steps":["trace[1463258592] 'process raft request' (duration: 138.003561ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:54.869562Z","caller":"traceutil/trace.go:171","msg":"trace[16606054] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"174.434683ms","start":"2026-04-10T13:42:54.695101Z","end":"2026-04-10T13:42:54.869536Z","steps":["trace[16606054] 'process raft request' (duration: 92.422255ms)","trace[16606054] 'compare' (duration: 81.864335ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:42:54.869597Z","caller":"traceutil/trace.go:171","msg":"trace[1162550255] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"119.584594ms","start":"2026-04-10T13:42:54.749984Z","end":"2026-04-10T13:42:54.869569Z","steps":["trace[1162550255] 'read index received' (duration: 37.552816ms)","trace[1162550255] 'applied index is now lower than readState.Index' (duration: 82.028648ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:42:54.869683Z","caller":"traceutil/trace.go:171","msg":"trace[515675246] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"115.593265ms","start":"2026-04-10T13:42:54.754071Z","end":"2026-04-10T13:42:54.869664Z","steps":["trace[515675246] 'process raft request' (duration: 115.413011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:42:54.929455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.387212ms","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-04-10T13:42:54.929521Z","caller":"traceutil/trace.go:171","msg":"trace[1914450054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"235.468074ms","start":"2026-04-10T13:42:54.694033Z","end":"2026-04-10T13:42:54.929502Z","steps":["trace[1914450054] 'agreement among raft nodes before linearized reading' (duration: 175.653687ms)","trace[1914450054] 'range keys from in-memory index tree' (duration: 59.682294ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:42:54.92944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.466634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-10T13:42:54.929605Z","caller":"traceutil/trace.go:171","msg":"trace[1785658264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"235.645287ms","start":"2026-04-10T13:42:54.693936Z","end":"2026-04-10T13:42:54.929582Z","steps":["trace[1785658264] 'agreement among raft nodes before linearized reading' (duration: 175.728488ms)","trace[1785658264] 'range keys from in-memory index tree' (duration: 59.610303ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:42:55.155385Z","caller":"traceutil/trace.go:171","msg":"trace[808354175] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"222.27542ms","start":"2026-04-10T13:42:54.933086Z","end":"2026-04-10T13:42:55.155362Z","steps":["trace[808354175] 'process raft request' (duration: 222.085316ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:42:55.192679Z","caller":"traceutil/trace.go:171","msg":"trace[1504401146] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1015; }","duration":"137.930937ms","start":"2026-04-10T13:42:55.054723Z","end":"2026-04-10T13:42:55.192653Z","steps":["trace[1504401146] 'read index received' (duration: 100.479537ms)","trace[1504401146] 'applied index is now lower than readState.Index' (duration: 37.45001ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:42:55.19278Z","caller":"traceutil/trace.go:171","msg":"trace[351314624] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"259.317062ms","start":"2026-04-10T13:42:54.933438Z","end":"2026-04-10T13:42:55.192755Z","steps":["trace[351314624] 'process raft request' (duration: 237.654914ms)","trace[351314624] 'compare' (duration: 21.424523ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:42:55.193138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.390436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T13:42:55.193216Z","caller":"traceutil/trace.go:171","msg":"trace[1433696199] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:981; }","duration":"138.509678ms","start":"2026-04-10T13:42:55.054689Z","end":"2026-04-10T13:42:55.193198Z","steps":["trace[1433696199] 'agreement among raft nodes before linearized reading' (duration: 138.372115ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:43:04.394626Z","caller":"traceutil/trace.go:171","msg":"trace[2088777988] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"123.893188ms","start":"2026-04-10T13:43:04.270706Z","end":"2026-04-10T13:43:04.394599Z","steps":["trace[2088777988] 'process raft request' (duration: 123.720665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:43:17.952221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.868869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T13:43:17.952302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.90955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:43:17.952311Z","caller":"traceutil/trace.go:171","msg":"trace[553702640] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1059; }","duration":"130.033812ms","start":"2026-04-10T13:43:17.82226Z","end":"2026-04-10T13:43:17.952294Z","steps":["trace[553702640] 'count revisions from in-memory index tree' (duration: 129.748037ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:43:17.952342Z","caller":"traceutil/trace.go:171","msg":"trace[1091496449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"164.952332ms","start":"2026-04-10T13:43:17.787377Z","end":"2026-04-10T13:43:17.95233Z","steps":["trace[1091496449] 'range keys from in-memory index tree' (duration: 164.787268ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:43:23.241649Z","caller":"traceutil/trace.go:171","msg":"trace[1899727849] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"107.566256ms","start":"2026-04-10T13:43:23.134062Z","end":"2026-04-10T13:43:23.241628Z","steps":["trace[1899727849] 'process raft request' (duration: 107.407513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:43:25.073337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.837366ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-10T13:43:25.073584Z","caller":"traceutil/trace.go:171","msg":"trace[944127674] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"166.906443ms","start":"2026-04-10T13:43:24.906659Z","end":"2026-04-10T13:43:25.073566Z","steps":["trace[944127674] 'process raft request' (duration: 166.77944ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:43:27.675283Z","caller":"traceutil/trace.go:171","msg":"trace[1232049918] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"111.22151ms","start":"2026-04-10T13:43:27.564039Z","end":"2026-04-10T13:43:27.675261Z","steps":["trace[1232049918] 'process raft request' (duration: 111.087768ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:43:27.896844Z","caller":"traceutil/trace.go:171","msg":"trace[1462442642] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"111.625877ms","start":"2026-04-10T13:43:27.785195Z","end":"2026-04-10T13:43:27.896821Z","steps":["trace[1462442642] 'read index received' (duration: 103.196467ms)","trace[1462442642] 'applied index is now lower than readState.Index' (duration: 8.42838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:43:27.896983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.78434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:43:27.897039Z","caller":"traceutil/trace.go:171","msg":"trace[929846123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"111.886522ms","start":"2026-04-10T13:43:27.785139Z","end":"2026-04-10T13:43:27.897025Z","steps":["trace[929846123] 'agreement among raft nodes before linearized reading' (duration: 111.805631ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:43:27.896959Z","caller":"traceutil/trace.go:171","msg":"trace[1675210789] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"216.75068ms","start":"2026-04-10T13:43:27.680133Z","end":"2026-04-10T13:43:27.896883Z","steps":["trace[1675210789] 'process raft request' (duration: 208.144326ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:43:31.540508Z","caller":"traceutil/trace.go:171","msg":"trace[1132054810] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"165.054528ms","start":"2026-04-10T13:43:31.375436Z","end":"2026-04-10T13:43:31.540491Z","steps":["trace[1132054810] 'process raft request' (duration: 164.952666ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:43:32.844038Z","caller":"traceutil/trace.go:171","msg":"trace[920372338] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"112.759552ms","start":"2026-04-10T13:43:32.731243Z","end":"2026-04-10T13:43:32.844002Z","steps":["trace[920372338] 'process raft request' (duration: 112.541238ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:43:39.761866Z","caller":"traceutil/trace.go:171","msg":"trace[381689742] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1175; }","duration":"123.348453ms","start":"2026-04-10T13:43:39.638494Z","end":"2026-04-10T13:43:39.761842Z","steps":["trace[381689742] 'read index received' (duration: 89.513918ms)","trace[381689742] 'applied index is now lower than readState.Index' (duration: 33.830345ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:43:39.76193Z","caller":"traceutil/trace.go:171","msg":"trace[1466744278] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"123.531116ms","start":"2026-04-10T13:43:39.638369Z","end":"2026-04-10T13:43:39.7619Z","steps":["trace[1466744278] 'process raft request' (duration: 89.62629ms)","trace[1466744278] 'compare' (duration: 33.731393ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:43:39.762061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.555146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-10T13:43:39.762103Z","caller":"traceutil/trace.go:171","msg":"trace[194324572] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1131; }","duration":"123.638298ms","start":"2026-04-10T13:43:39.638453Z","end":"2026-04-10T13:43:39.762091Z","steps":["trace[194324572] 'agreement among raft nodes before linearized reading' (duration: 123.482175ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:43:40.23287Z","caller":"traceutil/trace.go:171","msg":"trace[1373482374] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"244.974596ms","start":"2026-04-10T13:43:39.98783Z","end":"2026-04-10T13:43:40.232804Z","steps":["trace[1373482374] 'process raft request' (duration: 244.878325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:43:51.324074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.337491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4219"} {"level":"info","ts":"2026-04-10T13:43:51.324151Z","caller":"traceutil/trace.go:171","msg":"trace[45175957] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1167; }","duration":"113.460213ms","start":"2026-04-10T13:43:51.210671Z","end":"2026-04-10T13:43:51.324131Z","steps":["trace[45175957] 'range keys from in-memory index tree' (duration: 113.085416ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:44:01.348361Z","caller":"traceutil/trace.go:171","msg":"trace[746660456] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"143.135391ms","start":"2026-04-10T13:44:01.205203Z","end":"2026-04-10T13:44:01.348339Z","steps":["trace[746660456] 'process raft request' (duration: 143.032099ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:44:01.625857Z","caller":"traceutil/trace.go:171","msg":"trace[880418069] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"149.526347ms","start":"2026-04-10T13:44:01.476294Z","end":"2026-04-10T13:44:01.625821Z","steps":["trace[880418069] 'process raft request' (duration: 148.593121ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:44:10.324578Z","caller":"traceutil/trace.go:171","msg":"trace[1854017309] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"128.602371ms","start":"2026-04-10T13:44:10.195942Z","end":"2026-04-10T13:44:10.324544Z","steps":["trace[1854017309] 'process raft request' (duration: 128.395637ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:44:12.013053Z","caller":"traceutil/trace.go:171","msg":"trace[308613011] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"181.718514ms","start":"2026-04-10T13:44:11.831287Z","end":"2026-04-10T13:44:12.013005Z","steps":["trace[308613011] 'process raft request' (duration: 119.866945ms)","trace[308613011] 'compare' (duration: 61.709957ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:44:12.519763Z","caller":"traceutil/trace.go:171","msg":"trace[1637240960] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"187.436732ms","start":"2026-04-10T13:44:12.33229Z","end":"2026-04-10T13:44:12.519727Z","steps":["trace[1637240960] 'process raft request' (duration: 187.232429ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:44:22.184851Z","caller":"traceutil/trace.go:171","msg":"trace[1933408136] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"116.043285ms","start":"2026-04-10T13:44:22.06877Z","end":"2026-04-10T13:44:22.184814Z","steps":["trace[1933408136] 'process raft request' (duration: 115.623358ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:44:22.379169Z","caller":"traceutil/trace.go:171","msg":"trace[100349946] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"190.017034ms","start":"2026-04-10T13:44:22.189122Z","end":"2026-04-10T13:44:22.379139Z","steps":["trace[100349946] 'process raft request' (duration: 187.846449ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:44:29.781935Z","caller":"traceutil/trace.go:171","msg":"trace[1514771517] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"109.199529ms","start":"2026-04-10T13:44:29.672708Z","end":"2026-04-10T13:44:29.781908Z","steps":["trace[1514771517] 'process raft request' (duration: 93.894265ms)","trace[1514771517] 'compare' (duration: 14.895527ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:44:30.861312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.15588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-10T13:44:30.861375Z","caller":"traceutil/trace.go:171","msg":"trace[1717364031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1336; }","duration":"101.268772ms","start":"2026-04-10T13:44:30.760094Z","end":"2026-04-10T13:44:30.861363Z","steps":["trace[1717364031] 'range keys from in-memory index tree' (duration: 100.982228ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:44:31.914582Z","caller":"traceutil/trace.go:171","msg":"trace[837214187] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"121.728866ms","start":"2026-04-10T13:44:31.792825Z","end":"2026-04-10T13:44:31.914554Z","steps":["trace[837214187] 'process raft request' (duration: 121.580624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:44:32.319432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.836487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:44:32.319542Z","caller":"traceutil/trace.go:171","msg":"trace[1186053211] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"251.301273ms","start":"2026-04-10T13:44:32.068226Z","end":"2026-04-10T13:44:32.319527Z","steps":["trace[1186053211] 'read index received' (duration: 66.211µs)","trace[1186053211] 'applied index is now lower than readState.Index' (duration: 251.233442ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:44:32.319827Z","caller":"traceutil/trace.go:171","msg":"trace[2102106431] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"378.192301ms","start":"2026-04-10T13:44:31.941607Z","end":"2026-04-10T13:44:32.3198Z","steps":["trace[2102106431] 'process raft request' (duration: 63.866636ms)","trace[2102106431] 'compare' (duration: 313.569883ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:44:32.319844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.597797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T13:44:32.319941Z","caller":"traceutil/trace.go:171","msg":"trace[417881719] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1339; }","duration":"251.75508ms","start":"2026-04-10T13:44:32.068163Z","end":"2026-04-10T13:44:32.319918Z","steps":["trace[417881719] 'agreement among raft nodes before linearized reading' (duration: 251.555057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:44:32.31995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:44:31.941593Z","time spent":"378.291962ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:44:32.656008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.237826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T13:44:32.656112Z","caller":"traceutil/trace.go:171","msg":"trace[1308353482] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1340; }","duration":"116.372688ms","start":"2026-04-10T13:44:32.539718Z","end":"2026-04-10T13:44:32.656091Z","steps":["trace[1308353482] 'count revisions from in-memory index tree' (duration: 116.129425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:44:32.656012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.719972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-10T13:44:32.656241Z","caller":"traceutil/trace.go:171","msg":"trace[1714526825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1340; }","duration":"227.020886ms","start":"2026-04-10T13:44:32.429195Z","end":"2026-04-10T13:44:32.656216Z","steps":["trace[1714526825] 'range keys from in-memory index tree' (duration: 226.531288ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:44:33.26913Z","caller":"traceutil/trace.go:171","msg":"trace[1054088215] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"608.813666ms","start":"2026-04-10T13:44:32.660292Z","end":"2026-04-10T13:44:33.269105Z","steps":["trace[1054088215] 'process raft request' (duration: 608.647254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:44:33.269315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:44:32.660268Z","time spent":"608.940038ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:44:33.315783Z","caller":"traceutil/trace.go:171","msg":"trace[1773713524] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1396; }","duration":"528.800264ms","start":"2026-04-10T13:44:32.786954Z","end":"2026-04-10T13:44:33.315754Z","steps":["trace[1773713524] 'read index received' (duration: 481.87272ms)","trace[1773713524] 'applied index is now lower than readState.Index' (duration: 46.926094ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:44:33.315937Z","caller":"traceutil/trace.go:171","msg":"trace[2034128085] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"655.495807ms","start":"2026-04-10T13:44:32.660398Z","end":"2026-04-10T13:44:33.315894Z","steps":["trace[2034128085] 'process raft request' (duration: 655.198352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:44:33.315957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.984007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T13:44:33.316122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.754545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-10T13:44:33.316197Z","caller":"traceutil/trace.go:171","msg":"trace[495194575] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"372.871567ms","start":"2026-04-10T13:44:32.943311Z","end":"2026-04-10T13:44:33.316182Z","steps":["trace[495194575] 'agreement among raft nodes before linearized reading' (duration: 372.668714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:44:33.316141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:44:32.660366Z","time spent":"655.627719ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:44:33.316154Z","caller":"traceutil/trace.go:171","msg":"trace[1174563789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1342; }","duration":"529.081808ms","start":"2026-04-10T13:44:32.786909Z","end":"2026-04-10T13:44:33.315991Z","steps":["trace[1174563789] 'agreement among raft nodes before linearized reading' (duration: 528.991427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:44:33.316238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:44:32.943283Z","time spent":"372.946278ms","remote":"127.0.0.1:45770","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-04-10T13:44:33.316398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:44:32.786887Z","time spent":"529.493585ms","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-10T13:44:36.499528Z","caller":"traceutil/trace.go:171","msg":"trace[464641337] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"106.941721ms","start":"2026-04-10T13:44:36.392563Z","end":"2026-04-10T13:44:36.499505Z","steps":["trace[464641337] 'process raft request' (duration: 56.196149ms)","trace[464641337] 'compare' (duration: 50.633541ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:44:38.704014Z","caller":"traceutil/trace.go:171","msg":"trace[1987411939] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"195.925674ms","start":"2026-04-10T13:44:38.508056Z","end":"2026-04-10T13:44:38.703982Z","steps":["trace[1987411939] 'process raft request' (duration: 108.642695ms)","trace[1987411939] 'compare' (duration: 86.905623ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:44:41.719589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.055333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:835"} {"level":"info","ts":"2026-04-10T13:44:41.719645Z","caller":"traceutil/trace.go:171","msg":"trace[1628631508] range","detail":"{range_begin:/registry/clusterroles/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:1373; }","duration":"144.135876ms","start":"2026-04-10T13:44:41.575497Z","end":"2026-04-10T13:44:41.719633Z","steps":["trace[1628631508] 'range keys from in-memory index tree' (duration: 144.008391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:44:41.719741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.418751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:958"} {"level":"info","ts":"2026-04-10T13:44:41.719836Z","caller":"traceutil/trace.go:171","msg":"trace[763486855] range","detail":"{range_begin:/registry/clusterroles/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1373; }","duration":"109.552007ms","start":"2026-04-10T13:44:41.61026Z","end":"2026-04-10T13:44:41.719812Z","steps":["trace[763486855] 'range keys from in-memory index tree' (duration: 109.317987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:44:41.719747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.250465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-10T13:44:41.719913Z","caller":"traceutil/trace.go:171","msg":"trace[964030906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1373; }","duration":"147.449973ms","start":"2026-04-10T13:44:41.57245Z","end":"2026-04-10T13:44:41.7199Z","steps":["trace[964030906] 'range keys from in-memory index tree' (duration: 147.082847ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:44:44.84928Z","caller":"traceutil/trace.go:171","msg":"trace[716582363] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"112.793821ms","start":"2026-04-10T13:44:44.736463Z","end":"2026-04-10T13:44:44.849257Z","steps":["trace[716582363] 'process raft request' (duration: 112.560441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:44:45.134239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.702704ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-10T13:44:45.134339Z","caller":"traceutil/trace.go:171","msg":"trace[284590722] linearizableReadLoop","detail":"{readStateIndex:1442; appliedIndex:1441; }","duration":"136.912037ms","start":"2026-04-10T13:44:44.997408Z","end":"2026-04-10T13:44:45.13432Z","steps":["trace[284590722] 'read index received' (duration: 41.082µs)","trace[284590722] 'applied index is now lower than readState.Index' (duration: 136.868915ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:44:45.134479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.064203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:751"} {"level":"warn","ts":"2026-04-10T13:44:45.134496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.837566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replication-controller\" ","response":"range_response_count:1 size:828"} {"level":"info","ts":"2026-04-10T13:44:45.134515Z","caller":"traceutil/trace.go:171","msg":"trace[965454864] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:1383; }","duration":"137.132396ms","start":"2026-04-10T13:44:44.997366Z","end":"2026-04-10T13:44:45.134498Z","steps":["trace[965454864] 'agreement among raft nodes before linearized reading' (duration: 137.048933ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:44:45.134558Z","caller":"traceutil/trace.go:171","msg":"trace[1204873750] range","detail":"{range_begin:/registry/clusterroles/system:controller:replication-controller; range_end:; response_count:1; response_revision:1383; }","duration":"134.92444ms","start":"2026-04-10T13:44:44.999618Z","end":"2026-04-10T13:44:45.134542Z","steps":["trace[1204873750] 'agreement among raft nodes before linearized reading' (duration: 134.809875ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:44:45.924747Z","caller":"traceutil/trace.go:171","msg":"trace[879253546] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"115.460224ms","start":"2026-04-10T13:44:45.809261Z","end":"2026-04-10T13:44:45.924721Z","steps":["trace[879253546] 'read index received' (duration: 115.244175ms)","trace[879253546] 'applied index is now lower than readState.Index' (duration: 215.359µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:44:45.924836Z","caller":"traceutil/trace.go:171","msg":"trace[1604848469] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"124.998649ms","start":"2026-04-10T13:44:45.799829Z","end":"2026-04-10T13:44:45.924828Z","steps":["trace[1604848469] 'process raft request' (duration: 124.726307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:44:45.925006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.786719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-04-10T13:44:45.925104Z","caller":"traceutil/trace.go:171","msg":"trace[1207573089] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:1386; }","duration":"112.916394ms","start":"2026-04-10T13:44:45.812168Z","end":"2026-04-10T13:44:45.925084Z","steps":["trace[1207573089] 'agreement among raft nodes before linearized reading' (duration: 112.771288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:44:45.925036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.758598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:1 size:743"} {"level":"info","ts":"2026-04-10T13:44:45.925237Z","caller":"traceutil/trace.go:171","msg":"trace[288658574] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:1; response_revision:1386; }","duration":"115.948556ms","start":"2026-04-10T13:44:45.809255Z","end":"2026-04-10T13:44:45.925204Z","steps":["trace[288658574] 'agreement among raft nodes before linearized reading' (duration: 115.712966ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:45:06.759234Z","caller":"traceutil/trace.go:171","msg":"trace[1126841856] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"133.448662ms","start":"2026-04-10T13:45:06.625762Z","end":"2026-04-10T13:45:06.759211Z","steps":["trace[1126841856] 'process raft request' (duration: 133.255014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:45:08.395488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.298594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-10T13:45:08.395516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.737006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-9f2fn\" ","response":"range_response_count:1 size:4905"} {"level":"info","ts":"2026-04-10T13:45:08.395613Z","caller":"traceutil/trace.go:171","msg":"trace[410030920] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-9f2fn; range_end:; response_count:1; response_revision:1459; }","duration":"176.893982ms","start":"2026-04-10T13:45:08.2187Z","end":"2026-04-10T13:45:08.395593Z","steps":["trace[410030920] 'range keys from in-memory index tree' (duration: 176.550909ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:45:08.395622Z","caller":"traceutil/trace.go:171","msg":"trace[99404130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1459; }","duration":"157.482831ms","start":"2026-04-10T13:45:08.238115Z","end":"2026-04-10T13:45:08.395598Z","steps":["trace[99404130] 'range keys from in-memory index tree' (duration: 157.111066ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:45:08.840903Z","caller":"traceutil/trace.go:171","msg":"trace[879143669] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"215.978376ms","start":"2026-04-10T13:45:08.624904Z","end":"2026-04-10T13:45:08.840883Z","steps":["trace[879143669] 'process raft request' (duration: 215.854881ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:45:08.841098Z","caller":"traceutil/trace.go:171","msg":"trace[940285013] linearizableReadLoop","detail":"{readStateIndex:1524; appliedIndex:1524; }","duration":"162.725646ms","start":"2026-04-10T13:45:08.67834Z","end":"2026-04-10T13:45:08.841066Z","steps":["trace[940285013] 'read index received' (duration: 162.713916ms)","trace[940285013] 'applied index is now lower than readState.Index' (duration: 9.16µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:45:08.841357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.997338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-dtzk2\" ","response":"range_response_count:1 size:4908"} {"level":"info","ts":"2026-04-10T13:45:08.841404Z","caller":"traceutil/trace.go:171","msg":"trace[1576412078] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-dtzk2; range_end:; response_count:1; response_revision:1461; }","duration":"163.096971ms","start":"2026-04-10T13:45:08.678294Z","end":"2026-04-10T13:45:08.841391Z","steps":["trace[1576412078] 'agreement among raft nodes before linearized reading' (duration: 162.894083ms)"],"step_count":1}