{"level":"warn","ts":"2026-04-22T00:21:57.896464Z","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-22T00:21:57.89655Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-22T00:21:57.89663Z","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-22T00:21:57.896648Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-04-22T00:21:57.896675Z","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-22T00:21:57.897419Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-04-22T00:21:57.897517Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-22T00:21:57.905436Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.681374ms"} {"level":"info","ts":"2026-04-22T00:21:57.915283Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-04-22T00:21:57.915385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T00:21:57.91541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-04-22T00:21:57.915429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T00:21:57.915445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-04-22T00:21:57.915501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-04-22T00:21:57.92458Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T00:21:57.926636Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T00:21:57.929169Z","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-22T00:21:57.932955Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T00:21:57.934205Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T00:21:57.934277Z","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-22T00:21:57.934354Z","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-22T00:21:57.934362Z","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-22T00:21:57.936585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-04-22T00:21:57.93686Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-04-22T00:21:57.937788Z","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-22T00:21:57.937979Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T00:21:57.938011Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T00:21:57.938175Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-04-22T00:21:57.938225Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-04-22T00:21:58.915912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T00:21:58.916024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T00:21:58.916052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-04-22T00:21:58.916072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-04-22T00:21:58.916083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-04-22T00:21:58.916098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-04-22T00:21:58.916111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-04-22T00:21:58.917678Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T00:21:58.917703Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:21:58.917824Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T00:21:58.917949Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T00:21:58.918135Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T00:21:58.918152Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T00:21:58.921601Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-04-22T00:21:58.921987Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T00:21:58.969441Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:21:58.96968Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:21:58.969729Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:22:02.836683Z","caller":"traceutil/trace.go:171","msg":"trace[493827055] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"138.710295ms","start":"2026-04-22T00:22:02.697945Z","end":"2026-04-22T00:22:02.836655Z","steps":["trace[493827055] 'process raft request' (duration: 138.389486ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:03.01497Z","caller":"traceutil/trace.go:171","msg":"trace[1562325963] linearizableReadLoop","detail":"{readStateIndex:230; appliedIndex:228; }","duration":"162.05815ms","start":"2026-04-22T00:22:02.852879Z","end":"2026-04-22T00:22:03.014937Z","steps":["trace[1562325963] 'read index received' (duration: 49.770972ms)","trace[1562325963] 'applied index is now lower than readState.Index' (duration: 112.286488ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:03.015033Z","caller":"traceutil/trace.go:171","msg":"trace[1751400896] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"167.52738ms","start":"2026-04-22T00:22:02.847462Z","end":"2026-04-22T00:22:03.014989Z","steps":["trace[1751400896] 'process raft request' (duration: 95.608718ms)","trace[1751400896] 'compare' (duration: 71.769148ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:03.01511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.218024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:03.015173Z","caller":"traceutil/trace.go:171","msg":"trace[1287852811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:225; }","duration":"162.317391ms","start":"2026-04-22T00:22:02.852847Z","end":"2026-04-22T00:22:03.015165Z","steps":["trace[1287852811] 'agreement among raft nodes before linearized reading' (duration: 162.209235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:03.015367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.341826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-z6fmwa\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:03.015516Z","caller":"traceutil/trace.go:171","msg":"trace[1546991287] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-z6fmwa; range_end:; response_count:0; response_revision:225; }","duration":"109.563829ms","start":"2026-04-22T00:22:02.905903Z","end":"2026-04-22T00:22:03.015467Z","steps":["trace[1546991287] 'agreement among raft nodes before linearized reading' (duration: 109.255489ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:03.266363Z","caller":"traceutil/trace.go:171","msg":"trace[1503836505] linearizableReadLoop","detail":"{readStateIndex:235; appliedIndex:234; }","duration":"159.191895ms","start":"2026-04-22T00:22:03.107148Z","end":"2026-04-22T00:22:03.26634Z","steps":["trace[1503836505] 'read index received' (duration: 147.60261ms)","trace[1503836505] 'applied index is now lower than readState.Index' (duration: 11.588055ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:03.266505Z","caller":"traceutil/trace.go:171","msg":"trace[1977383003] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"160.76216ms","start":"2026-04-22T00:22:03.105717Z","end":"2026-04-22T00:22:03.266479Z","steps":["trace[1977383003] 'process raft request' (duration: 149.02004ms)","trace[1977383003] 'compare' (duration: 11.45315ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:03.26659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.430947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-22T00:22:03.266644Z","caller":"traceutil/trace.go:171","msg":"trace[1700865615] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:230; }","duration":"159.524883ms","start":"2026-04-22T00:22:03.107103Z","end":"2026-04-22T00:22:03.266628Z","steps":["trace[1700865615] 'agreement among raft nodes before linearized reading' (duration: 159.33669ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:04.103041Z","caller":"traceutil/trace.go:171","msg":"trace[903087257] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:247; }","duration":"106.139764ms","start":"2026-04-22T00:22:03.99687Z","end":"2026-04-22T00:22:04.103009Z","steps":["trace[903087257] 'read index received' (duration: 67.20711ms)","trace[903087257] 'applied index is now lower than readState.Index' (duration: 38.931504ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:04.103128Z","caller":"traceutil/trace.go:171","msg":"trace[509778377] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"123.801248ms","start":"2026-04-22T00:22:03.979285Z","end":"2026-04-22T00:22:04.103086Z","steps":["trace[509778377] 'process raft request' (duration: 84.855475ms)","trace[509778377] 'compare' (duration: 38.718901ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:04.103286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.403616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-22T00:22:04.103394Z","caller":"traceutil/trace.go:171","msg":"trace[1714427336] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:243; }","duration":"106.494512ms","start":"2026-04-22T00:22:03.996849Z","end":"2026-04-22T00:22:04.103343Z","steps":["trace[1714427336] 'agreement among raft nodes before linearized reading' (duration: 106.288318ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:07.246733Z","caller":"traceutil/trace.go:171","msg":"trace[941878285] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"139.31658ms","start":"2026-04-22T00:22:07.107391Z","end":"2026-04-22T00:22:07.246708Z","steps":["trace[941878285] 'process raft request' (duration: 139.181533ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:07.310179Z","caller":"traceutil/trace.go:171","msg":"trace[1938115308] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:313; }","duration":"200.224835ms","start":"2026-04-22T00:22:07.10993Z","end":"2026-04-22T00:22:07.310155Z","steps":["trace[1938115308] 'read index received' (duration: 136.662606ms)","trace[1938115308] 'applied index is now lower than readState.Index' (duration: 63.561259ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:07.310201Z","caller":"traceutil/trace.go:171","msg":"trace[243006441] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"202.167359ms","start":"2026-04-22T00:22:07.107996Z","end":"2026-04-22T00:22:07.310163Z","steps":["trace[243006441] 'process raft request' (duration: 200.771919ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:07.310289Z","caller":"traceutil/trace.go:171","msg":"trace[802826104] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"202.032683ms","start":"2026-04-22T00:22:07.108227Z","end":"2026-04-22T00:22:07.31026Z","steps":["trace[802826104] 'process raft request' (duration: 201.860898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:07.310351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.398821ms","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-22T00:22:07.310391Z","caller":"traceutil/trace.go:171","msg":"trace[1669678826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:305; }","duration":"200.480718ms","start":"2026-04-22T00:22:07.109897Z","end":"2026-04-22T00:22:07.310378Z","steps":["trace[1669678826] 'agreement among raft nodes before linearized reading' (duration: 200.392891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:07.310529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.182934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-04-22T00:22:07.310628Z","caller":"traceutil/trace.go:171","msg":"trace[1301698804] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:305; }","duration":"184.32225ms","start":"2026-04-22T00:22:07.126288Z","end":"2026-04-22T00:22:07.31061Z","steps":["trace[1301698804] 'agreement among raft nodes before linearized reading' (duration: 184.168435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:07.310637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.898317ms","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-22T00:22:07.310723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.692901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-22T00:22:07.310806Z","caller":"traceutil/trace.go:171","msg":"trace[1292834941] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:305; }","duration":"162.817757ms","start":"2026-04-22T00:22:07.147969Z","end":"2026-04-22T00:22:07.310787Z","steps":["trace[1292834941] 'agreement among raft nodes before linearized reading' (duration: 162.700201ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:07.310795Z","caller":"traceutil/trace.go:171","msg":"trace[1905648510] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:305; }","duration":"113.036883ms","start":"2026-04-22T00:22:07.197727Z","end":"2026-04-22T00:22:07.310763Z","steps":["trace[1905648510] 'agreement among raft nodes before linearized reading' (duration: 112.873528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:08.15189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.396185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:22:08.151996Z","caller":"traceutil/trace.go:171","msg":"trace[336367727] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:341; }","duration":"421.199929ms","start":"2026-04-22T00:22:07.73078Z","end":"2026-04-22T00:22:08.15198Z","steps":["trace[336367727] 'read index received' (duration: 68.348µs)","trace[336367727] 'applied index is now lower than readState.Index' (duration: 421.130401ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:08.152041Z","caller":"traceutil/trace.go:171","msg":"trace[928233698] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"544.816158ms","start":"2026-04-22T00:22:07.607193Z","end":"2026-04-22T00:22:08.152009Z","steps":["trace[928233698] 'process raft request' (duration: 17.82199ms)","trace[928233698] 'compare' (duration: 526.234249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:08.152105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.322505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-04-22T00:22:08.152144Z","caller":"traceutil/trace.go:171","msg":"trace[28082068] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:331; }","duration":"421.408813ms","start":"2026-04-22T00:22:07.730723Z","end":"2026-04-22T00:22:08.152131Z","steps":["trace[28082068] 'agreement among raft nodes before linearized reading' (duration: 421.303146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:08.152188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:07.730699Z","time spent":"421.478171ms","remote":"127.0.0.1:40758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-04-22T00:22:08.152293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:07.607167Z","time spent":"544.944934ms","remote":"127.0.0.1:41020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:08.658246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315361000168967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:22:09.158663Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315361000168967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:22:09.436879Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.280776581s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-22T00:22:09.77683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.639724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:22:09.776942Z","caller":"traceutil/trace.go:171","msg":"trace[1358139299] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"1.620923572s","start":"2026-04-22T00:22:08.155996Z","end":"2026-04-22T00:22:09.77692Z","steps":["trace[1358139299] 'process raft request' (duration: 1.281121402s)","trace[1358139299] 'compare' (duration: 339.221945ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:09.777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:08.155976Z","time spent":"1.621003071s","remote":"127.0.0.1:40650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:09.78001Z","caller":"traceutil/trace.go:171","msg":"trace[1964361393] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:342; }","duration":"1.622225672s","start":"2026-04-22T00:22:08.157753Z","end":"2026-04-22T00:22:09.779978Z","steps":["trace[1964361393] 'read index received' (duration: 1.279375659s)","trace[1964361393] 'applied index is now lower than readState.Index' (duration: 342.848813ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:09.780076Z","caller":"traceutil/trace.go:171","msg":"trace[881794903] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"1.620923977s","start":"2026-04-22T00:22:08.159133Z","end":"2026-04-22T00:22:09.780057Z","steps":["trace[881794903] 'process raft request' (duration: 1.620646724s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:09.780283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496787616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-22T00:22:09.780297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622514324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-22T00:22:09.780329Z","caller":"traceutil/trace.go:171","msg":"trace[280045694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:333; }","duration":"1.496874984s","start":"2026-04-22T00:22:08.283444Z","end":"2026-04-22T00:22:09.780319Z","steps":["trace[280045694] 'agreement among raft nodes before linearized reading' (duration: 1.496744017s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:09.78038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:08.283426Z","time spent":"1.496946642s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T00:22:09.780359Z","caller":"traceutil/trace.go:171","msg":"trace[2017936082] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:333; }","duration":"1.622635351s","start":"2026-04-22T00:22:08.157705Z","end":"2026-04-22T00:22:09.780341Z","steps":["trace[2017936082] 'agreement among raft nodes before linearized reading' (duration: 1.622422567s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:09.780524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.754417ms","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-22T00:22:09.780656Z","caller":"traceutil/trace.go:171","msg":"trace[1572973031] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:333; }","duration":"603.894574ms","start":"2026-04-22T00:22:09.176739Z","end":"2026-04-22T00:22:09.780634Z","steps":["trace[1572973031] 'agreement among raft nodes before linearized reading' (duration: 603.599581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:09.780676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.397799803s","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-22T00:22:09.780742Z","caller":"traceutil/trace.go:171","msg":"trace[851218992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:333; }","duration":"1.397902421s","start":"2026-04-22T00:22:08.382821Z","end":"2026-04-22T00:22:09.780724Z","steps":["trace[851218992] 'agreement among raft nodes before linearized reading' (duration: 1.397733035s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:09.780728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228333542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-22T00:22:09.780735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.203347ms","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-22T00:22:09.780795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:08.382802Z","time spent":"1.397980598s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T00:22:09.780811Z","caller":"traceutil/trace.go:171","msg":"trace[974289577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:333; }","duration":"1.228467449s","start":"2026-04-22T00:22:08.552321Z","end":"2026-04-22T00:22:09.780789Z","steps":["trace[974289577] 'agreement among raft nodes before linearized reading' (duration: 1.228292653s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:09.780867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:08.552301Z","time spent":"1.228556066s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T00:22:09.780861Z","caller":"traceutil/trace.go:171","msg":"trace[946994529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:333; }","duration":"459.336793ms","start":"2026-04-22T00:22:09.321474Z","end":"2026-04-22T00:22:09.780811Z","steps":["trace[946994529] 'agreement among raft nodes before linearized reading' (duration: 459.145718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:09.780879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:08.159107Z","time spent":"1.621002625s","remote":"127.0.0.1:40992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:09.780978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:09.321454Z","time spent":"459.47699ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T00:22:09.781122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:08.157694Z","time spent":"1.623413121s","remote":"127.0.0.1:40758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-04-22T00:22:09.97025Z","caller":"traceutil/trace.go:171","msg":"trace[1093604022] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"184.634362ms","start":"2026-04-22T00:22:09.785581Z","end":"2026-04-22T00:22:09.970216Z","steps":["trace[1093604022] 'process raft request' (duration: 103.137854ms)","trace[1093604022] 'compare' (duration: 81.167827ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:09.976328Z","caller":"traceutil/trace.go:171","msg":"trace[1038981548] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:344; }","duration":"188.858234ms","start":"2026-04-22T00:22:09.787457Z","end":"2026-04-22T00:22:09.976316Z","steps":["trace[1038981548] 'read index received' (duration: 101.3348ms)","trace[1038981548] 'applied index is now lower than readState.Index' (duration: 87.522784ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:09.976352Z","caller":"traceutil/trace.go:171","msg":"trace[688074263] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"189.936956ms","start":"2026-04-22T00:22:09.786377Z","end":"2026-04-22T00:22:09.976314Z","steps":["trace[688074263] 'process raft request' (duration: 189.851208ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:09.97636Z","caller":"traceutil/trace.go:171","msg":"trace[165900046] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"188.921982ms","start":"2026-04-22T00:22:09.787423Z","end":"2026-04-22T00:22:09.976345Z","steps":["trace[165900046] 'process raft request' (duration: 188.832714ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:09.976506Z","caller":"traceutil/trace.go:171","msg":"trace[1357052928] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"186.65687ms","start":"2026-04-22T00:22:09.789839Z","end":"2026-04-22T00:22:09.976496Z","steps":["trace[1357052928] 'process raft request' (duration: 186.448116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:09.976662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.154656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-22T00:22:09.976691Z","caller":"traceutil/trace.go:171","msg":"trace[679267553] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:339; }","duration":"189.249963ms","start":"2026-04-22T00:22:09.787433Z","end":"2026-04-22T00:22:09.976683Z","steps":["trace[679267553] 'agreement among raft nodes before linearized reading' (duration: 188.925332ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:09.976521Z","caller":"traceutil/trace.go:171","msg":"trace[1672843212] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"190.296747ms","start":"2026-04-22T00:22:09.786197Z","end":"2026-04-22T00:22:09.976494Z","steps":["trace[1672843212] 'process raft request' (duration: 190.005135ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:09.976289Z","caller":"traceutil/trace.go:171","msg":"trace[449409382] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"190.257938ms","start":"2026-04-22T00:22:09.786003Z","end":"2026-04-22T00:22:09.976261Z","steps":["trace[449409382] 'process raft request' (duration: 190.118902ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:12.019634Z","caller":"traceutil/trace.go:171","msg":"trace[117288122] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"120.560147ms","start":"2026-04-22T00:22:11.898728Z","end":"2026-04-22T00:22:12.019288Z","steps":["trace[117288122] 'process raft request' (duration: 70.299569ms)","trace[117288122] 'compare' (duration: 50.020443ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:15.076199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.582707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4791"} {"level":"info","ts":"2026-04-22T00:22:15.076271Z","caller":"traceutil/trace.go:171","msg":"trace[828609318] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:371; }","duration":"452.690925ms","start":"2026-04-22T00:22:14.623558Z","end":"2026-04-22T00:22:15.076249Z","steps":["trace[828609318] 'range keys from in-memory index tree' (duration: 452.497549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:15.076309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:14.623539Z","time spent":"452.761604ms","remote":"127.0.0.1:40746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4815,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-04-22T00:22:15.076458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.124834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:15.076527Z","caller":"traceutil/trace.go:171","msg":"trace[2134281518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"336.246442ms","start":"2026-04-22T00:22:14.740262Z","end":"2026-04-22T00:22:15.076509Z","steps":["trace[2134281518] 'range keys from in-memory index tree' (duration: 335.977427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:15.076565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:14.740231Z","time spent":"336.326331ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T00:22:15.376023Z","caller":"traceutil/trace.go:171","msg":"trace[49375044] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"370.923648ms","start":"2026-04-22T00:22:15.00508Z","end":"2026-04-22T00:22:15.376004Z","steps":["trace[49375044] 'process raft request' (duration: 370.735571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:15.376113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.252482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:15.376154Z","caller":"traceutil/trace.go:171","msg":"trace[1697323612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:372; }","duration":"298.350321ms","start":"2026-04-22T00:22:15.077791Z","end":"2026-04-22T00:22:15.376141Z","steps":["trace[1697323612] 'agreement among raft nodes before linearized reading' (duration: 298.277112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:15.376159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:15.005057Z","time spent":"371.034905ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:15.375962Z","caller":"traceutil/trace.go:171","msg":"trace[1714298435] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"298.093815ms","start":"2026-04-22T00:22:15.077842Z","end":"2026-04-22T00:22:15.375936Z","steps":["trace[1714298435] 'read index received' (duration: 298.058926ms)","trace[1714298435] 'applied index is now lower than readState.Index' (duration: 33.999µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:16.142963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.561334ms","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-22T00:22:16.143081Z","caller":"traceutil/trace.go:171","msg":"trace[1087039975] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:372; }","duration":"109.687782ms","start":"2026-04-22T00:22:16.033376Z","end":"2026-04-22T00:22:16.143063Z","steps":["trace[1087039975] 'range keys from in-memory index tree' (duration: 109.545974ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:16.149882Z","caller":"traceutil/trace.go:171","msg":"trace[1682563131] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"1.064345152s","start":"2026-04-22T00:22:15.085438Z","end":"2026-04-22T00:22:16.149783Z","steps":["trace[1682563131] 'process raft request' (duration: 1.064210994s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:16.151059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:15.085417Z","time spent":"1.065530902s","remote":"127.0.0.1:40746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:16.154508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.393374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:16.154582Z","caller":"traceutil/trace.go:171","msg":"trace[578970497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:373; }","duration":"413.492682ms","start":"2026-04-22T00:22:15.741059Z","end":"2026-04-22T00:22:16.154552Z","steps":["trace[578970497] 'agreement among raft nodes before linearized reading' (duration: 413.394453ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:16.154499Z","caller":"traceutil/trace.go:171","msg":"trace[1023434974] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:386; }","duration":"413.287115ms","start":"2026-04-22T00:22:15.7411Z","end":"2026-04-22T00:22:16.154387Z","steps":["trace[1023434974] 'read index received' (duration: 409.127022ms)","trace[1023434974] 'applied index is now lower than readState.Index' (duration: 4.158343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:16.155296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:15.741038Z","time spent":"414.163681ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T00:22:16.326756Z","caller":"traceutil/trace.go:171","msg":"trace[1599784440] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"168.222569ms","start":"2026-04-22T00:22:16.158508Z","end":"2026-04-22T00:22:16.326731Z","steps":["trace[1599784440] 'process raft request' (duration: 159.696775ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:16.388323Z","caller":"traceutil/trace.go:171","msg":"trace[292298455] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"226.643298ms","start":"2026-04-22T00:22:16.161656Z","end":"2026-04-22T00:22:16.388299Z","steps":["trace[292298455] 'process raft request' (duration: 226.595199ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:16.388576Z","caller":"traceutil/trace.go:171","msg":"trace[1693989922] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"229.229927ms","start":"2026-04-22T00:22:16.15932Z","end":"2026-04-22T00:22:16.38855Z","steps":["trace[1693989922] 'process raft request' (duration: 228.895962ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:16.388651Z","caller":"traceutil/trace.go:171","msg":"trace[447923495] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"230.062003ms","start":"2026-04-22T00:22:16.158576Z","end":"2026-04-22T00:22:16.388637Z","steps":["trace[447923495] 'process raft request' (duration: 229.444123ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:16.388791Z","caller":"traceutil/trace.go:171","msg":"trace[904997081] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"229.978105ms","start":"2026-04-22T00:22:16.158688Z","end":"2026-04-22T00:22:16.388666Z","steps":["trace[904997081] 'process raft request' (duration: 229.495343ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:18.480386Z","caller":"traceutil/trace.go:171","msg":"trace[2087588553] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"145.896963ms","start":"2026-04-22T00:22:18.334463Z","end":"2026-04-22T00:22:18.48036Z","steps":["trace[2087588553] 'process raft request' (duration: 145.720075ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:18.653824Z","caller":"traceutil/trace.go:171","msg":"trace[1780415848] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"168.720058ms","start":"2026-04-22T00:22:18.485077Z","end":"2026-04-22T00:22:18.653797Z","steps":["trace[1780415848] 'process raft request' (duration: 73.666496ms)","trace[1780415848] 'compare' (duration: 94.886194ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:18.654136Z","caller":"traceutil/trace.go:171","msg":"trace[1906978174] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"168.828897ms","start":"2026-04-22T00:22:18.485268Z","end":"2026-04-22T00:22:18.654097Z","steps":["trace[1906978174] 'process raft request' (duration: 168.490461ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:18.920949Z","caller":"traceutil/trace.go:171","msg":"trace[2020252173] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"253.602454ms","start":"2026-04-22T00:22:18.66732Z","end":"2026-04-22T00:22:18.920922Z","steps":["trace[2020252173] 'process raft request' (duration: 231.811875ms)","trace[2020252173] 'compare' (duration: 21.677521ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:20.79084Z","caller":"traceutil/trace.go:171","msg":"trace[413722090] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:397; }","duration":"276.913776ms","start":"2026-04-22T00:22:20.513902Z","end":"2026-04-22T00:22:20.790815Z","steps":["trace[413722090] 'read index received' (duration: 276.730668ms)","trace[413722090] 'applied index is now lower than readState.Index' (duration: 182.258µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:20.791164Z","caller":"traceutil/trace.go:171","msg":"trace[1751101009] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"300.54906ms","start":"2026-04-22T00:22:20.490603Z","end":"2026-04-22T00:22:20.791152Z","steps":["trace[1751101009] 'process raft request' (duration: 300.089315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:20.791285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:20.490581Z","time spent":"300.63474ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:20.791424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.386983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T00:22:20.791664Z","caller":"traceutil/trace.go:171","msg":"trace[18037811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:384; }","duration":"133.61867ms","start":"2026-04-22T00:22:20.657969Z","end":"2026-04-22T00:22:20.791587Z","steps":["trace[18037811] 'agreement among raft nodes before linearized reading' (duration: 133.186164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:20.791841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.894846ms","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-22T00:22:20.791946Z","caller":"traceutil/trace.go:171","msg":"trace[987815422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:384; }","duration":"278.087253ms","start":"2026-04-22T00:22:20.513841Z","end":"2026-04-22T00:22:20.791928Z","steps":["trace[987815422] 'agreement among raft nodes before linearized reading' (duration: 277.865375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:20.791826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.789808ms","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-22T00:22:20.79199Z","caller":"traceutil/trace.go:171","msg":"trace[1586965121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:384; }","duration":"133.975836ms","start":"2026-04-22T00:22:20.657999Z","end":"2026-04-22T00:22:20.791975Z","steps":["trace[1586965121] 'agreement among raft nodes before linearized reading' (duration: 133.760879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.378984Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315361000169072,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:22:22.758973Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.963753414s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-22T00:22:23.262247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.932375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:22:23.262351Z","caller":"traceutil/trace.go:171","msg":"trace[558885221] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"2.467202384s","start":"2026-04-22T00:22:20.795132Z","end":"2026-04-22T00:22:23.262334Z","steps":["trace[558885221] 'process raft request' (duration: 1.96410895s)","trace[558885221] 'compare' (duration: 502.583807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:23.262418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:20.795121Z","time spent":"2.467265823s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:23.282411Z","caller":"traceutil/trace.go:171","msg":"trace[947700279] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"2.485212962s","start":"2026-04-22T00:22:20.797169Z","end":"2026-04-22T00:22:23.282382Z","steps":["trace[947700279] 'process raft request' (duration: 2.484988553s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:23.28276Z","caller":"traceutil/trace.go:171","msg":"trace[1457883188] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:398; }","duration":"1.403972846s","start":"2026-04-22T00:22:21.878768Z","end":"2026-04-22T00:22:23.282741Z","steps":["trace[1457883188] 'read index received' (duration: 880.56638ms)","trace[1457883188] 'applied index is now lower than readState.Index' (duration: 523.405036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:23.282799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:20.797142Z","time spent":"2.48535027s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:23.282831Z","caller":"traceutil/trace.go:171","msg":"trace[649905084] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"2.475718996s","start":"2026-04-22T00:22:20.807086Z","end":"2026-04-22T00:22:23.282805Z","steps":["trace[649905084] 'process raft request' (duration: 2.475416028s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.28296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404181025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-22T00:22:23.283017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.837525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T00:22:23.283025Z","caller":"traceutil/trace.go:171","msg":"trace[1203079365] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:389; }","duration":"1.404302173s","start":"2026-04-22T00:22:21.878704Z","end":"2026-04-22T00:22:23.283007Z","steps":["trace[1203079365] 'agreement among raft nodes before linearized reading' (duration: 1.404175034s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.282964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.251283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T00:22:23.283088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:21.878686Z","time spent":"1.404384233s","remote":"127.0.0.1:40590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"info","ts":"2026-04-22T00:22:23.283074Z","caller":"traceutil/trace.go:171","msg":"trace[1481808926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"486.883185ms","start":"2026-04-22T00:22:22.796173Z","end":"2026-04-22T00:22:23.283056Z","steps":["trace[1481808926] 'agreement among raft nodes before linearized reading' (duration: 486.820226ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:23.28243Z","caller":"traceutil/trace.go:171","msg":"trace[1585979583] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"2.483385221s","start":"2026-04-22T00:22:20.79898Z","end":"2026-04-22T00:22:23.282365Z","steps":["trace[1585979583] 'process raft request' (duration: 2.483313852s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.283194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.796098Z","time spent":"487.061273ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T00:22:23.283275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:20.79896Z","time spent":"2.484224295s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:23.282664Z","caller":"traceutil/trace.go:171","msg":"trace[310418489] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"2.48261477s","start":"2026-04-22T00:22:20.800023Z","end":"2026-04-22T00:22:23.282638Z","steps":["trace[310418489] 'process raft request' (duration: 2.482304002s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.282967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:20.807068Z","time spent":"2.475829575s","remote":"127.0.0.1:40992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:23.283115Z","caller":"traceutil/trace.go:171","msg":"trace[777710066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"545.434392ms","start":"2026-04-22T00:22:22.737668Z","end":"2026-04-22T00:22:23.283102Z","steps":["trace[777710066] 'agreement among raft nodes before linearized reading' (duration: 545.261623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.283703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:20.800012Z","time spent":"2.483611313s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:23.283807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.73765Z","time spent":"546.099857ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T00:22:23.483117Z","caller":"traceutil/trace.go:171","msg":"trace[293338778] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"137.840264ms","start":"2026-04-22T00:22:23.345251Z","end":"2026-04-22T00:22:23.483092Z","steps":["trace[293338778] 'process raft request' (duration: 41.002024ms)","trace[293338778] 'compare' (duration: 96.609772ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:23.483116Z","caller":"traceutil/trace.go:171","msg":"trace[538603470] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"137.773794ms","start":"2026-04-22T00:22:23.345312Z","end":"2026-04-22T00:22:23.483086Z","steps":["trace[538603470] 'process raft request' (duration: 137.707235ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:23.489109Z","caller":"traceutil/trace.go:171","msg":"trace[1980008264] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"138.725827ms","start":"2026-04-22T00:22:23.350368Z","end":"2026-04-22T00:22:23.489094Z","steps":["trace[1980008264] 'process raft request' (duration: 138.633838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.773388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.378635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:22:23.773555Z","caller":"traceutil/trace.go:171","msg":"trace[2107672842] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:415; }","duration":"217.244099ms","start":"2026-04-22T00:22:23.556294Z","end":"2026-04-22T00:22:23.773538Z","steps":["trace[2107672842] 'read index received' (duration: 94.731665ms)","trace[2107672842] 'applied index is now lower than readState.Index' (duration: 122.511224ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:23.773643Z","caller":"traceutil/trace.go:171","msg":"trace[711691150] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"248.473274ms","start":"2026-04-22T00:22:23.52516Z","end":"2026-04-22T00:22:23.773633Z","steps":["trace[711691150] 'process raft request' (duration: 125.754061ms)","trace[711691150] 'compare' (duration: 122.252456ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:23.77377Z","caller":"traceutil/trace.go:171","msg":"trace[664999642] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"217.499848ms","start":"2026-04-22T00:22:23.556262Z","end":"2026-04-22T00:22:23.773762Z","steps":["trace[664999642] 'process raft request' (duration: 217.22356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.774051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.728686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-z7bnj\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-04-22T00:22:23.774135Z","caller":"traceutil/trace.go:171","msg":"trace[1263962049] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-z7bnj; range_end:; response_count:1; response_revision:402; }","duration":"217.855435ms","start":"2026-04-22T00:22:23.556257Z","end":"2026-04-22T00:22:23.774113Z","steps":["trace[1263962049] 'agreement among raft nodes before linearized reading' (duration: 217.666407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.774261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.504745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-22T00:22:23.774305Z","caller":"traceutil/trace.go:171","msg":"trace[636243092] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:402; }","duration":"216.568605ms","start":"2026-04-22T00:22:23.557723Z","end":"2026-04-22T00:22:23.774291Z","steps":["trace[636243092] 'agreement among raft nodes before linearized reading' (duration: 216.454076ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:23.948719Z","caller":"traceutil/trace.go:171","msg":"trace[844047828] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"100.501194ms","start":"2026-04-22T00:22:23.848168Z","end":"2026-04-22T00:22:23.948669Z","steps":["trace[844047828] 'process raft request' (duration: 63.138294ms)","trace[844047828] 'compare' (duration: 37.089252ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:25.560206Z","caller":"traceutil/trace.go:171","msg":"trace[368455858] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"272.269703ms","start":"2026-04-22T00:22:25.287907Z","end":"2026-04-22T00:22:25.560176Z","steps":["trace[368455858] 'read index received' (duration: 272.084684ms)","trace[368455858] 'applied index is now lower than readState.Index' (duration: 183.429µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:25.56032Z","caller":"traceutil/trace.go:171","msg":"trace[1006674044] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"290.737232ms","start":"2026-04-22T00:22:25.269531Z","end":"2026-04-22T00:22:25.560268Z","steps":["trace[1006674044] 'process raft request' (duration: 290.436493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:25.560471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.528951ms","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-22T00:22:25.560509Z","caller":"traceutil/trace.go:171","msg":"trace[1466726196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:420; }","duration":"272.61766ms","start":"2026-04-22T00:22:25.287885Z","end":"2026-04-22T00:22:25.560502Z","steps":["trace[1466726196] 'agreement among raft nodes before linearized reading' (duration: 272.461341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:25.78269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.379279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:22:25.782873Z","caller":"traceutil/trace.go:171","msg":"trace[1819368412] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"390.523693ms","start":"2026-04-22T00:22:25.392325Z","end":"2026-04-22T00:22:25.782849Z","steps":["trace[1819368412] 'process raft request' (duration: 214.997954ms)","trace[1819368412] 'compare' (duration: 175.15748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:25.783013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:25.3923Z","time spent":"390.646932ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:25.783029Z","caller":"traceutil/trace.go:171","msg":"trace[1992604251] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"222.681867ms","start":"2026-04-22T00:22:25.560305Z","end":"2026-04-22T00:22:25.782987Z","steps":["trace[1992604251] 'read index received' (duration: 46.990389ms)","trace[1992604251] 'applied index is now lower than readState.Index' (duration: 175.686698ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:25.783086Z","caller":"traceutil/trace.go:171","msg":"trace[1689302142] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"218.291257ms","start":"2026-04-22T00:22:25.564773Z","end":"2026-04-22T00:22:25.783064Z","steps":["trace[1689302142] 'process raft request' (duration: 218.073759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:25.783259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.092879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T00:22:25.783305Z","caller":"traceutil/trace.go:171","msg":"trace[767954712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:422; }","duration":"495.180628ms","start":"2026-04-22T00:22:25.288113Z","end":"2026-04-22T00:22:25.783293Z","steps":["trace[767954712] 'agreement among raft nodes before linearized reading' (duration: 495.007239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:25.783349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:25.288101Z","time spent":"495.238187ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T00:22:25.783481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.593805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T00:22:25.783617Z","caller":"traceutil/trace.go:171","msg":"trace[718464667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"438.755545ms","start":"2026-04-22T00:22:25.344851Z","end":"2026-04-22T00:22:25.783607Z","steps":["trace[718464667] 'agreement among raft nodes before linearized reading' (duration: 438.509176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:25.783673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:25.344836Z","time spent":"438.828375ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T00:22:25.946589Z","caller":"traceutil/trace.go:171","msg":"trace[1785642973] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"157.293347ms","start":"2026-04-22T00:22:25.789257Z","end":"2026-04-22T00:22:25.946551Z","steps":["trace[1785642973] 'process raft request' (duration: 157.233357ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:25.946917Z","caller":"traceutil/trace.go:171","msg":"trace[2072708131] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"158.80981ms","start":"2026-04-22T00:22:25.788095Z","end":"2026-04-22T00:22:25.946905Z","steps":["trace[2072708131] 'process raft request' (duration: 131.936023ms)","trace[2072708131] 'compare' (duration: 26.220891ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:27.739009Z","caller":"traceutil/trace.go:171","msg":"trace[1241114639] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"170.260116ms","start":"2026-04-22T00:22:27.568727Z","end":"2026-04-22T00:22:27.738987Z","steps":["trace[1241114639] 'process raft request' (duration: 169.988807ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:28.061013Z","caller":"traceutil/trace.go:171","msg":"trace[816196310] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"105.82081ms","start":"2026-04-22T00:22:27.955164Z","end":"2026-04-22T00:22:28.060985Z","steps":["trace[816196310] 'process raft request' (duration: 105.76089ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:28.061281Z","caller":"traceutil/trace.go:171","msg":"trace[1929112002] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"106.417559ms","start":"2026-04-22T00:22:27.954836Z","end":"2026-04-22T00:22:28.061254Z","steps":["trace[1929112002] 'process raft request' (duration: 20.171656ms)","trace[1929112002] 'compare' (duration: 85.700523ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:34.509483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.753609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-04-22T00:22:34.509583Z","caller":"traceutil/trace.go:171","msg":"trace[679110519] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:442; }","duration":"261.86591ms","start":"2026-04-22T00:22:34.247693Z","end":"2026-04-22T00:22:34.509559Z","steps":["trace[679110519] 'range keys from in-memory index tree' (duration: 261.609239ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:35.544026Z","caller":"traceutil/trace.go:171","msg":"trace[1853504922] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"257.406552ms","start":"2026-04-22T00:22:35.286596Z","end":"2026-04-22T00:22:35.544003Z","steps":["trace[1853504922] 'process raft request' (duration: 256.743268ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:35.6763Z","caller":"traceutil/trace.go:171","msg":"trace[2093590233] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"107.830868ms","start":"2026-04-22T00:22:35.568412Z","end":"2026-04-22T00:22:35.676243Z","steps":["trace[2093590233] 'process raft request' (duration: 86.429297ms)","trace[2093590233] 'compare' (duration: 21.22307ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:35.949771Z","caller":"traceutil/trace.go:171","msg":"trace[700480992] linearizableReadLoop","detail":"{readStateIndex:465; appliedIndex:464; }","duration":"209.396071ms","start":"2026-04-22T00:22:35.740344Z","end":"2026-04-22T00:22:35.94974Z","steps":["trace[700480992] 'read index received' (duration: 196.533099ms)","trace[700480992] 'applied index is now lower than readState.Index' (duration: 12.861722ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:35.949829Z","caller":"traceutil/trace.go:171","msg":"trace[667121634] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"255.727931ms","start":"2026-04-22T00:22:35.694075Z","end":"2026-04-22T00:22:35.949803Z","steps":["trace[667121634] 'process raft request' (duration: 242.897639ms)","trace[667121634] 'compare' (duration: 12.50378ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:35.950478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.093365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:35.950584Z","caller":"traceutil/trace.go:171","msg":"trace[637690953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"210.219895ms","start":"2026-04-22T00:22:35.740306Z","end":"2026-04-22T00:22:35.950526Z","steps":["trace[637690953] 'agreement among raft nodes before linearized reading' (duration: 209.570102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:35.951016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.90068ms","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-22T00:22:35.951074Z","caller":"traceutil/trace.go:171","msg":"trace[390756290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:448; }","duration":"125.989501ms","start":"2026-04-22T00:22:35.825072Z","end":"2026-04-22T00:22:35.951061Z","steps":["trace[390756290] 'agreement among raft nodes before linearized reading' (duration: 125.88636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.270877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250665086s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:22:39.271152Z","caller":"traceutil/trace.go:171","msg":"trace[1993725928] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:532; }","duration":"1.383255553s","start":"2026-04-22T00:22:37.887876Z","end":"2026-04-22T00:22:39.271132Z","steps":["trace[1993725928] 'read index received' (duration: 132.317465ms)","trace[1993725928] 'applied index is now lower than readState.Index' (duration: 1.250936518s)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:39.271302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426552331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"info","ts":"2026-04-22T00:22:39.271343Z","caller":"traceutil/trace.go:171","msg":"trace[1290064080] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:511; }","duration":"1.426609771s","start":"2026-04-22T00:22:37.844722Z","end":"2026-04-22T00:22:39.271332Z","steps":["trace[1290064080] 'agreement among raft nodes before linearized reading' (duration: 1.42649081s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:39.271312Z","caller":"traceutil/trace.go:171","msg":"trace[1799443208] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.410950382s","start":"2026-04-22T00:22:37.860295Z","end":"2026-04-22T00:22:39.271245Z","steps":["trace[1799443208] 'process raft request' (duration: 1.41074208s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.27153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426800183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-04-22T00:22:39.271573Z","caller":"traceutil/trace.go:171","msg":"trace[38302847] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:511; }","duration":"1.426853083s","start":"2026-04-22T00:22:37.844709Z","end":"2026-04-22T00:22:39.271562Z","steps":["trace[38302847] 'agreement among raft nodes before linearized reading' (duration: 1.426704592s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.271606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:37.844702Z","time spent":"1.426896774s","remote":"127.0.0.1:41062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1037,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-22T00:22:39.271387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:37.844717Z","time spent":"1.426660971s","remote":"127.0.0.1:41062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1019,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-22T00:22:39.271686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427009955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:1019"} {"level":"info","ts":"2026-04-22T00:22:39.27177Z","caller":"traceutil/trace.go:171","msg":"trace[1441732323] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:511; }","duration":"1.427168666s","start":"2026-04-22T00:22:37.844581Z","end":"2026-04-22T00:22:39.27175Z","steps":["trace[1441732323] 'agreement among raft nodes before linearized reading' (duration: 1.426890143s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.271856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:37.844564Z","time spent":"1.427284547s","remote":"127.0.0.1:41062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1043,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" "} {"level":"info","ts":"2026-04-22T00:22:39.271374Z","caller":"traceutil/trace.go:171","msg":"trace[1345109009] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.419307146s","start":"2026-04-22T00:22:37.852012Z","end":"2026-04-22T00:22:39.271319Z","steps":["trace[1345109009] 'process raft request' (duration: 168.113425ms)","trace[1345109009] 'compare' (duration: 1.250508164s)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:39.271659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:37.860273Z","time spent":"1.411214225s","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:39.272004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:37.851994Z","time spent":"1.419903851s","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:39.314655Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.294089125s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T00:22:39.315677Z","caller":"traceutil/trace.go:171","msg":"trace[1123966275] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.366110386s","start":"2026-04-22T00:22:37.949529Z","end":"2026-04-22T00:22:39.31564Z","steps":["trace[1123966275] 'process raft request' (duration: 1.366072956s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.315839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:37.949498Z","time spent":"1.366246878s","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:39.315995Z","caller":"traceutil/trace.go:171","msg":"trace[750284234] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.368404524s","start":"2026-04-22T00:22:37.947564Z","end":"2026-04-22T00:22:39.315968Z","steps":["trace[750284234] 'process raft request' (duration: 1.367742628s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.316221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:37.947539Z","time spent":"1.368546965s","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:39.811672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.563349ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-22T00:22:39.812898Z","caller":"traceutil/trace.go:171","msg":"trace[228289088] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"526.82935ms","start":"2026-04-22T00:22:39.28582Z","end":"2026-04-22T00:22:39.812649Z","steps":["trace[228289088] 'process raft request' (duration: 526.223504ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:39.812339Z","caller":"traceutil/trace.go:171","msg":"trace[1180651009] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"477.937849ms","start":"2026-04-22T00:22:39.334381Z","end":"2026-04-22T00:22:39.812319Z","steps":["trace[1180651009] 'process raft request' (duration: 477.779567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.813104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.6315889s","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-22T00:22:39.813164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:39.334358Z","time spent":"478.750796ms","remote":"127.0.0.1:41062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:39.81323Z","caller":"traceutil/trace.go:171","msg":"trace[1994449745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"1.631753761s","start":"2026-04-22T00:22:38.181448Z","end":"2026-04-22T00:22:39.813202Z","steps":["trace[1994449745] 'agreement among raft nodes before linearized reading' (duration: 1.631537189s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.813299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:38.181428Z","time spent":"1.631852093s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T00:22:39.813557Z","caller":"traceutil/trace.go:171","msg":"trace[1692913987] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"531.591164ms","start":"2026-04-22T00:22:39.281904Z","end":"2026-04-22T00:22:39.813495Z","steps":["trace[1692913987] 'process raft request' (duration: 529.913908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.81375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:39.281882Z","time spent":"531.741126ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:39.814097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:39.285802Z","time spent":"527.538636ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:39.812324Z","caller":"traceutil/trace.go:171","msg":"trace[645217459] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:534; }","duration":"541.094163ms","start":"2026-04-22T00:22:39.271186Z","end":"2026-04-22T00:22:39.81228Z","steps":["trace[645217459] 'read index received' (duration: 43.679731ms)","trace[645217459] 'applied index is now lower than readState.Index' (duration: 497.410742ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:39.814301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.828164734s","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-22T00:22:39.814361Z","caller":"traceutil/trace.go:171","msg":"trace[1142967681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"1.828242485s","start":"2026-04-22T00:22:37.986099Z","end":"2026-04-22T00:22:39.814341Z","steps":["trace[1142967681] 'agreement among raft nodes before linearized reading' (duration: 1.828113464s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.814527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:37.986059Z","time spent":"1.828358866s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T00:22:39.814084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.626009074s","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-22T00:22:39.814711Z","caller":"traceutil/trace.go:171","msg":"trace[380727417] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"414.710214ms","start":"2026-04-22T00:22:39.399968Z","end":"2026-04-22T00:22:39.814678Z","steps":["trace[380727417] 'process raft request' (duration: 412.293701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.81494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:39.399937Z","time spent":"414.833975ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:39.815038Z","caller":"traceutil/trace.go:171","msg":"trace[1442836381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"1.626758591s","start":"2026-04-22T00:22:38.187993Z","end":"2026-04-22T00:22:39.814751Z","steps":["trace[1442836381] 'agreement among raft nodes before linearized reading' (duration: 1.625559929s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.815127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:38.187986Z","time spent":"1.627111454s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T00:22:39.819507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.855333169s","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-22T00:22:39.819592Z","caller":"traceutil/trace.go:171","msg":"trace[1316591417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"1.85545122s","start":"2026-04-22T00:22:37.964108Z","end":"2026-04-22T00:22:39.819559Z","steps":["trace[1316591417] 'agreement among raft nodes before linearized reading' (duration: 1.855276728s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.819846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:37.964088Z","time spent":"1.855660563s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T00:22:40.63502Z","caller":"traceutil/trace.go:171","msg":"trace[1116758099] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"324.043964ms","start":"2026-04-22T00:22:40.310951Z","end":"2026-04-22T00:22:40.634995Z","steps":["trace[1116758099] 'process raft request' (duration: 307.518834ms)","trace[1116758099] 'compare' (duration: 16.395639ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:40.635262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:40.310928Z","time spent":"324.256226ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:40.650711Z","caller":"traceutil/trace.go:171","msg":"trace[342531341] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"339.026819ms","start":"2026-04-22T00:22:40.311646Z","end":"2026-04-22T00:22:40.650672Z","steps":["trace[342531341] 'process raft request' (duration: 338.586595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:40.650913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:40.311636Z","time spent":"339.158771ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:40.651827Z","caller":"traceutil/trace.go:171","msg":"trace[1852251048] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"258.292956ms","start":"2026-04-22T00:22:40.393513Z","end":"2026-04-22T00:22:40.651806Z","steps":["trace[1852251048] 'process raft request' (duration: 257.459707ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:41.081009Z","caller":"traceutil/trace.go:171","msg":"trace[2032620238] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"180.598424ms","start":"2026-04-22T00:22:40.90036Z","end":"2026-04-22T00:22:41.080959Z","steps":["trace[2032620238] 'process raft request' (duration: 129.142067ms)","trace[2032620238] 'compare' (duration: 51.079782ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:41.088049Z","caller":"traceutil/trace.go:171","msg":"trace[370434558] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"151.398728ms","start":"2026-04-22T00:22:40.936585Z","end":"2026-04-22T00:22:41.087984Z","steps":["trace[370434558] 'process raft request' (duration: 144.567172ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:42.292255Z","caller":"traceutil/trace.go:171","msg":"trace[1802086464] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"411.77092ms","start":"2026-04-22T00:22:41.880456Z","end":"2026-04-22T00:22:42.292227Z","steps":["trace[1802086464] 'read index received' (duration: 351.583313ms)","trace[1802086464] 'applied index is now lower than readState.Index' (duration: 60.186457ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:42.292596Z","caller":"traceutil/trace.go:171","msg":"trace[1026963483] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"437.476929ms","start":"2026-04-22T00:22:41.855103Z","end":"2026-04-22T00:22:42.29258Z","steps":["trace[1026963483] 'process raft request' (duration: 376.929098ms)","trace[1026963483] 'compare' (duration: 59.798882ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:42.292798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.317727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-22T00:22:42.292853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:41.855089Z","time spent":"437.611731ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:42.292881Z","caller":"traceutil/trace.go:171","msg":"trace[732724958] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:580; }","duration":"412.452359ms","start":"2026-04-22T00:22:41.88041Z","end":"2026-04-22T00:22:42.292862Z","steps":["trace[732724958] 'agreement among raft nodes before linearized reading' (duration: 412.186856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:42.292931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:41.88039Z","time spent":"412.531759ms","remote":"127.0.0.1:40590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"info","ts":"2026-04-22T00:22:42.310866Z","caller":"traceutil/trace.go:171","msg":"trace[665537794] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"170.39858ms","start":"2026-04-22T00:22:42.140444Z","end":"2026-04-22T00:22:42.310843Z","steps":["trace[665537794] 'process raft request' (duration: 170.302929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:42.311264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.189253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-22T00:22:42.311301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.916491ms","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-22T00:22:42.311334Z","caller":"traceutil/trace.go:171","msg":"trace[1528832611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"397.297985ms","start":"2026-04-22T00:22:41.914026Z","end":"2026-04-22T00:22:42.311324Z","steps":["trace[1528832611] 'agreement among raft nodes before linearized reading' (duration: 397.155133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:42.311434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:41.914012Z","time spent":"397.413026ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T00:22:42.311355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.80064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:42.311523Z","caller":"traceutil/trace.go:171","msg":"trace[241757017] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:581; }","duration":"373.007063ms","start":"2026-04-22T00:22:41.938503Z","end":"2026-04-22T00:22:42.31151Z","steps":["trace[241757017] 'agreement among raft nodes before linearized reading' (duration: 372.809901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:42.31157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:41.938483Z","time spent":"373.073304ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-04-22T00:22:42.311594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.315686ms","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-22T00:22:42.311716Z","caller":"traceutil/trace.go:171","msg":"trace[1037012048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"398.512878ms","start":"2026-04-22T00:22:41.913175Z","end":"2026-04-22T00:22:42.311688Z","steps":["trace[1037012048] 'agreement among raft nodes before linearized reading' (duration: 397.990662ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:42.311385Z","caller":"traceutil/trace.go:171","msg":"trace[561614101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"398.037093ms","start":"2026-04-22T00:22:41.913324Z","end":"2026-04-22T00:22:42.311361Z","steps":["trace[561614101] 'agreement among raft nodes before linearized reading' (duration: 397.891292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:42.311773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:41.913296Z","time spent":"398.468398ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T00:22:42.31178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:41.913153Z","time spent":"398.6178ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T00:22:43.840523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315361000169441,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:22:43.893865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.924604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:43.893955Z","caller":"traceutil/trace.go:171","msg":"trace[302340804] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:601; }","duration":"755.046005ms","start":"2026-04-22T00:22:43.138883Z","end":"2026-04-22T00:22:43.893929Z","steps":["trace[302340804] 'range keys from in-memory index tree' (duration: 754.848473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:43.894002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:43.138867Z","time spent":"755.124766ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-22T00:22:43.910236Z","caller":"traceutil/trace.go:171","msg":"trace[1385721830] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"570.326001ms","start":"2026-04-22T00:22:43.339887Z","end":"2026-04-22T00:22:43.910213Z","steps":["trace[1385721830] 'read index received' (duration: 566.452291ms)","trace[1385721830] 'applied index is now lower than readState.Index' (duration: 3.87284ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:43.910341Z","caller":"traceutil/trace.go:171","msg":"trace[276987179] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"422.943161ms","start":"2026-04-22T00:22:43.48739Z","end":"2026-04-22T00:22:43.910333Z","steps":["trace[276987179] 'process raft request' (duration: 422.768048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:43.910419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:43.487359Z","time spent":"423.004512ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:43.910579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.666325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T00:22:43.910647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.345893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-22T00:22:43.910652Z","caller":"traceutil/trace.go:171","msg":"trace[2048136340] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"678.387311ms","start":"2026-04-22T00:22:43.232228Z","end":"2026-04-22T00:22:43.910615Z","steps":["trace[2048136340] 'process raft request' (duration: 674.070455ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:43.910726Z","caller":"traceutil/trace.go:171","msg":"trace[611392172] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:603; }","duration":"371.459274ms","start":"2026-04-22T00:22:43.539245Z","end":"2026-04-22T00:22:43.910704Z","steps":["trace[611392172] 'agreement among raft nodes before linearized reading' (duration: 371.250641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:43.910734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.57308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-22T00:22:43.910773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.262869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-04-22T00:22:43.910644Z","caller":"traceutil/trace.go:171","msg":"trace[1847867342] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:603; }","duration":"570.786067ms","start":"2026-04-22T00:22:43.339843Z","end":"2026-04-22T00:22:43.910629Z","steps":["trace[1847867342] 'agreement among raft nodes before linearized reading' (duration: 570.651675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:43.910783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:43.539227Z","time spent":"371.542615ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4204,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-04-22T00:22:43.910789Z","caller":"traceutil/trace.go:171","msg":"trace[690677395] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:603; }","duration":"136.654631ms","start":"2026-04-22T00:22:43.77412Z","end":"2026-04-22T00:22:43.910775Z","steps":["trace[690677395] 'agreement among raft nodes before linearized reading' (duration: 136.500909ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:43.910813Z","caller":"traceutil/trace.go:171","msg":"trace[1958138754] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:603; }","duration":"171.337821ms","start":"2026-04-22T00:22:43.739468Z","end":"2026-04-22T00:22:43.910806Z","steps":["trace[1958138754] 'agreement among raft nodes before linearized reading' (duration: 171.224229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:43.910824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:43.339816Z","time spent":"570.99844ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-22T00:22:43.910825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:43.232198Z","time spent":"678.525324ms","remote":"127.0.0.1:40674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036011,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:43.911243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.942835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:43.911346Z","caller":"traceutil/trace.go:171","msg":"trace[406959918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"171.087587ms","start":"2026-04-22T00:22:43.740228Z","end":"2026-04-22T00:22:43.911316Z","steps":["trace[406959918] 'agreement among raft nodes before linearized reading' (duration: 170.432009ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:44.592283Z","caller":"traceutil/trace.go:171","msg":"trace[355069426] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"145.257546ms","start":"2026-04-22T00:22:44.446996Z","end":"2026-04-22T00:22:44.592253Z","steps":["trace[355069426] 'process raft request' (duration: 144.13195ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:44.788363Z","caller":"traceutil/trace.go:171","msg":"trace[798311478] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"134.076921ms","start":"2026-04-22T00:22:44.654262Z","end":"2026-04-22T00:22:44.788339Z","steps":["trace[798311478] 'process raft request' (duration: 92.369355ms)","trace[798311478] 'compare' (duration: 41.448993ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:45.064891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.804469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-22T00:22:45.064972Z","caller":"traceutil/trace.go:171","msg":"trace[747967795] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:616; }","duration":"125.932691ms","start":"2026-04-22T00:22:44.939022Z","end":"2026-04-22T00:22:45.064955Z","steps":["trace[747967795] 'range keys from in-memory index tree' (duration: 125.669296ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:45.532051Z","caller":"traceutil/trace.go:171","msg":"trace[167646669] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"216.602613ms","start":"2026-04-22T00:22:45.315423Z","end":"2026-04-22T00:22:45.532026Z","steps":["trace[167646669] 'process raft request' (duration: 216.41096ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:45.57644Z","caller":"traceutil/trace.go:171","msg":"trace[1792893917] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"208.965251ms","start":"2026-04-22T00:22:45.367449Z","end":"2026-04-22T00:22:45.576415Z","steps":["trace[1792893917] 'process raft request' (duration: 208.637196ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:45.67514Z","caller":"traceutil/trace.go:171","msg":"trace[1681165160] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"106.734814ms","start":"2026-04-22T00:22:45.568385Z","end":"2026-04-22T00:22:45.67512Z","steps":["trace[1681165160] 'process raft request' (duration: 48.181676ms)","trace[1681165160] 'compare' (duration: 58.432256ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:45.894603Z","caller":"traceutil/trace.go:171","msg":"trace[2081879218] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"122.98699ms","start":"2026-04-22T00:22:45.771594Z","end":"2026-04-22T00:22:45.894581Z","steps":["trace[2081879218] 'process raft request' (duration: 122.710006ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:45.944673Z","caller":"traceutil/trace.go:171","msg":"trace[2054287759] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"160.521741ms","start":"2026-04-22T00:22:45.784115Z","end":"2026-04-22T00:22:45.944637Z","steps":["trace[2054287759] 'process raft request' (duration: 160.205306ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:50.638651Z","caller":"traceutil/trace.go:171","msg":"trace[1716713309] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"186.956227ms","start":"2026-04-22T00:22:50.451669Z","end":"2026-04-22T00:22:50.638625Z","steps":["trace[1716713309] 'read index received' (duration: 186.802114ms)","trace[1716713309] 'applied index is now lower than readState.Index' (duration: 152.913µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:50.638765Z","caller":"traceutil/trace.go:171","msg":"trace[1250085852] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"188.281641ms","start":"2026-04-22T00:22:50.450451Z","end":"2026-04-22T00:22:50.638732Z","steps":["trace[1250085852] 'process raft request' (duration: 188.010486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:50.63886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.175661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T00:22:50.638904Z","caller":"traceutil/trace.go:171","msg":"trace[2085931232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"187.274453ms","start":"2026-04-22T00:22:50.451618Z","end":"2026-04-22T00:22:50.638893Z","steps":["trace[2085931232] 'agreement among raft nodes before linearized reading' (duration: 187.11022ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:50.750773Z","caller":"traceutil/trace.go:171","msg":"trace[1518868209] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"107.76841ms","start":"2026-04-22T00:22:50.642973Z","end":"2026-04-22T00:22:50.750742Z","steps":["trace[1518868209] 'process raft request' (duration: 74.978919ms)","trace[1518868209] 'compare' (duration: 32.535066ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:51.971044Z","caller":"traceutil/trace.go:171","msg":"trace[420188866] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"195.347023ms","start":"2026-04-22T00:22:51.775669Z","end":"2026-04-22T00:22:51.971016Z","steps":["trace[420188866] 'process raft request' (duration: 195.114698ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:52.394335Z","caller":"traceutil/trace.go:171","msg":"trace[1348143842] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"302.47067ms","start":"2026-04-22T00:22:52.091823Z","end":"2026-04-22T00:22:52.394294Z","steps":["trace[1348143842] 'read index received' (duration: 293.974899ms)","trace[1348143842] 'applied index is now lower than readState.Index' (duration: 8.492801ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:52.394442Z","caller":"traceutil/trace.go:171","msg":"trace[355566831] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"410.192637ms","start":"2026-04-22T00:22:51.984226Z","end":"2026-04-22T00:22:52.394419Z","steps":["trace[355566831] 'process raft request' (duration: 401.633145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:52.394551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:51.984211Z","time spent":"410.267259ms","remote":"127.0.0.1:40590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:52.394668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.502013ms","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-22T00:22:52.394764Z","caller":"traceutil/trace.go:171","msg":"trace[1946358714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"260.635876ms","start":"2026-04-22T00:22:52.134112Z","end":"2026-04-22T00:22:52.394748Z","steps":["trace[1946358714] 'agreement among raft nodes before linearized reading' (duration: 260.391241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:52.394727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.675334ms","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-22T00:22:52.394935Z","caller":"traceutil/trace.go:171","msg":"trace[1538245300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:661; }","duration":"303.098332ms","start":"2026-04-22T00:22:52.091816Z","end":"2026-04-22T00:22:52.394915Z","steps":["trace[1538245300] 'agreement among raft nodes before linearized reading' (duration: 302.600902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:52.394992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:52.091732Z","time spent":"303.246465ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T00:22:53.73092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.05559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T00:22:53.730999Z","caller":"traceutil/trace.go:171","msg":"trace[1188615156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"977.194722ms","start":"2026-04-22T00:22:52.753786Z","end":"2026-04-22T00:22:53.730981Z","steps":["trace[1188615156] 'range keys from in-memory index tree' (duration: 976.837265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:53.73098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.655064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T00:22:53.73104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:52.753768Z","time spent":"977.263404ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T00:22:53.731053Z","caller":"traceutil/trace.go:171","msg":"trace[1127297328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"989.769506ms","start":"2026-04-22T00:22:52.741265Z","end":"2026-04-22T00:22:53.731034Z","steps":["trace[1127297328] 'range keys from in-memory index tree' (duration: 989.526021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:53.731094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:52.741246Z","time spent":"989.839037ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T00:22:53.731244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088658051s","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-22T00:22:53.731326Z","caller":"traceutil/trace.go:171","msg":"trace[447819374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:664; }","duration":"1.088788323s","start":"2026-04-22T00:22:52.642517Z","end":"2026-04-22T00:22:53.731305Z","steps":["trace[447819374] 'range keys from in-memory index tree' (duration: 1.088474547s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:53.731367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:52.642488Z","time spent":"1.088870395s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T00:22:54.946975Z","caller":"traceutil/trace.go:171","msg":"trace[516654265] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"160.814529ms","start":"2026-04-22T00:22:54.786137Z","end":"2026-04-22T00:22:54.946952Z","steps":["trace[516654265] 'process raft request' (duration: 160.694457ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:57.988204Z","caller":"traceutil/trace.go:171","msg":"trace[1324557773] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"189.621564ms","start":"2026-04-22T00:22:57.798532Z","end":"2026-04-22T00:22:57.988153Z","steps":["trace[1324557773] 'process raft request' (duration: 160.310148ms)","trace[1324557773] 'compare' (duration: 29.07462ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:57.992067Z","caller":"traceutil/trace.go:171","msg":"trace[973724243] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"130.758395ms","start":"2026-04-22T00:22:57.861283Z","end":"2026-04-22T00:22:57.992041Z","steps":["trace[973724243] 'process raft request' (duration: 130.477748ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:58.555551Z","caller":"traceutil/trace.go:171","msg":"trace[1407054514] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"110.179352ms","start":"2026-04-22T00:22:58.445349Z","end":"2026-04-22T00:22:58.555529Z","steps":["trace[1407054514] 'process raft request' (duration: 110.049018ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:58.561112Z","caller":"traceutil/trace.go:171","msg":"trace[590747647] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"114.036346ms","start":"2026-04-22T00:22:58.447044Z","end":"2026-04-22T00:22:58.561081Z","steps":["trace[590747647] 'process raft request' (duration: 113.873622ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:00.019289Z","caller":"traceutil/trace.go:171","msg":"trace[1065580875] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"199.551469ms","start":"2026-04-22T00:22:59.819711Z","end":"2026-04-22T00:23:00.019262Z","steps":["trace[1065580875] 'process raft request' (duration: 199.408426ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:00.055951Z","caller":"traceutil/trace.go:171","msg":"trace[396820162] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:730; }","duration":"206.548701ms","start":"2026-04-22T00:22:59.849384Z","end":"2026-04-22T00:23:00.055933Z","steps":["trace[396820162] 'read index received' (duration: 169.719181ms)","trace[396820162] 'applied index is now lower than readState.Index' (duration: 36.82848ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:00.056116Z","caller":"traceutil/trace.go:171","msg":"trace[490288970] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"206.891059ms","start":"2026-04-22T00:22:59.849182Z","end":"2026-04-22T00:23:00.056073Z","steps":["trace[490288970] 'process raft request' (duration: 206.634493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:00.056432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.035313ms","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-22T00:23:00.056506Z","caller":"traceutil/trace.go:171","msg":"trace[1887619943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:704; }","duration":"207.179356ms","start":"2026-04-22T00:22:59.849309Z","end":"2026-04-22T00:23:00.056488Z","steps":["trace[1887619943] 'agreement among raft nodes before linearized reading' (duration: 206.693623ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:01.952421Z","caller":"traceutil/trace.go:171","msg":"trace[455716214] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"111.372513ms","start":"2026-04-22T00:23:01.841014Z","end":"2026-04-22T00:23:01.952387Z","steps":["trace[455716214] 'process raft request' (duration: 111.135516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:10.483865Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315361000169889,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:23:10.9845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315361000169889,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:23:11.380635Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.508789892s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T00:23:11.381059Z","caller":"traceutil/trace.go:171","msg":"trace[1089974210] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:811; }","duration":"1.397819092s","start":"2026-04-22T00:23:09.983202Z","end":"2026-04-22T00:23:11.381021Z","steps":["trace[1089974210] 'read index received' (duration: 1.397807471s)","trace[1089974210] 'applied index is now lower than readState.Index' (duration: 9.041µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:11.381036Z","caller":"traceutil/trace.go:171","msg":"trace[1794273195] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"1.509304088s","start":"2026-04-22T00:23:09.871707Z","end":"2026-04-22T00:23:11.381011Z","steps":["trace[1794273195] 'process raft request' (duration: 1.509147813s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:11.381268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:09.871681Z","time spent":"1.509496105s","remote":"127.0.0.1:40746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:23:11.381389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.083879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T00:23:11.381467Z","caller":"traceutil/trace.go:171","msg":"trace[775718442] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:779; }","duration":"616.211433ms","start":"2026-04-22T00:23:10.765243Z","end":"2026-04-22T00:23:11.381454Z","steps":["trace[775718442] 'agreement among raft nodes before linearized reading' (duration: 616.101969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:11.381462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150062803s","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-22T00:23:11.381514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.263722ms","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-22T00:23:11.381555Z","caller":"traceutil/trace.go:171","msg":"trace[890040387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:779; }","duration":"1.150233278s","start":"2026-04-22T00:23:10.231302Z","end":"2026-04-22T00:23:11.381536Z","steps":["trace[890040387] 'agreement among raft nodes before linearized reading' (duration: 1.14996723s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:11.381565Z","caller":"traceutil/trace.go:171","msg":"trace[1280570392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:779; }","duration":"718.366145ms","start":"2026-04-22T00:23:10.663184Z","end":"2026-04-22T00:23:11.381551Z","steps":["trace[1280570392] 'agreement among raft nodes before linearized reading' (duration: 718.2136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:11.381604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:10.663154Z","time spent":"718.442987ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T00:23:11.381607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:10.231282Z","time spent":"1.150314951s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T00:23:11.381625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.899522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-22T00:23:11.381578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:10.765222Z","time spent":"616.344227ms","remote":"127.0.0.1:41008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-04-22T00:23:11.381718Z","caller":"traceutil/trace.go:171","msg":"trace[1595466860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:779; }","duration":"713.031865ms","start":"2026-04-22T00:23:10.668654Z","end":"2026-04-22T00:23:11.381686Z","steps":["trace[1595466860] 'agreement among raft nodes before linearized reading' (duration: 712.725355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:11.38173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.068523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:11.381764Z","caller":"traceutil/trace.go:171","msg":"trace[1914180606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"640.149036ms","start":"2026-04-22T00:23:10.741606Z","end":"2026-04-22T00:23:11.381755Z","steps":["trace[1914180606] 'agreement among raft nodes before linearized reading' (duration: 640.097884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:11.381771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:10.668626Z","time spent":"713.132529ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T00:23:11.381797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:10.741574Z","time spent":"640.218078ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T00:23:11.381919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398111091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T00:23:11.382012Z","caller":"traceutil/trace.go:171","msg":"trace[880557848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"1.398851725s","start":"2026-04-22T00:23:09.983139Z","end":"2026-04-22T00:23:11.38199Z","steps":["trace[880557848] 'agreement among raft nodes before linearized reading' (duration: 1.398053509s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:11.382078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.960253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T00:23:11.382229Z","caller":"traceutil/trace.go:171","msg":"trace[1062657427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:779; }","duration":"713.076607ms","start":"2026-04-22T00:23:10.66903Z","end":"2026-04-22T00:23:11.382106Z","steps":["trace[1062657427] 'agreement among raft nodes before linearized reading' (duration: 712.300432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:11.38227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:10.66901Z","time spent":"713.250853ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T00:23:11.382289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:09.983115Z","time spent":"1.399153094s","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T00:23:13.186953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.418571ms","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-22T00:23:13.187051Z","caller":"traceutil/trace.go:171","msg":"trace[1680748641] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:787; }","duration":"108.579706ms","start":"2026-04-22T00:23:13.078449Z","end":"2026-04-22T00:23:13.187028Z","steps":["trace[1680748641] 'count revisions from in-memory index tree' (duration: 108.327098ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:13.769046Z","caller":"traceutil/trace.go:171","msg":"trace[1984130757] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"356.743598ms","start":"2026-04-22T00:23:13.412279Z","end":"2026-04-22T00:23:13.769022Z","steps":["trace[1984130757] 'process raft request' (duration: 356.540211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:13.769212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:13.412262Z","time spent":"356.858962ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:13.769401Z","caller":"traceutil/trace.go:171","msg":"trace[1379979559] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"357.063449ms","start":"2026-04-22T00:23:13.412314Z","end":"2026-04-22T00:23:13.769378Z","steps":["trace[1379979559] 'process raft request' (duration: 356.665475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:13.76958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:13.412293Z","time spent":"357.197913ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:13.769857Z","caller":"traceutil/trace.go:171","msg":"trace[2081621444] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"356.550681ms","start":"2026-04-22T00:23:13.413267Z","end":"2026-04-22T00:23:13.769818Z","steps":["trace[2081621444] 'process raft request' (duration: 355.978342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:13.77014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:13.41326Z","time spent":"356.750108ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:19.720545Z","caller":"traceutil/trace.go:171","msg":"trace[1332658575] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"105.128693ms","start":"2026-04-22T00:23:19.615389Z","end":"2026-04-22T00:23:19.720518Z","steps":["trace[1332658575] 'process raft request' (duration: 74.410801ms)","trace[1332658575] 'compare' (duration: 30.024397ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:25.82069Z","caller":"traceutil/trace.go:171","msg":"trace[641211362] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"273.331264ms","start":"2026-04-22T00:23:25.547335Z","end":"2026-04-22T00:23:25.820667Z","steps":["trace[641211362] 'read index received' (duration: 273.091505ms)","trace[641211362] 'applied index is now lower than readState.Index' (duration: 238.369µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:25.820774Z","caller":"traceutil/trace.go:171","msg":"trace[1165474022] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"399.464727ms","start":"2026-04-22T00:23:25.421279Z","end":"2026-04-22T00:23:25.820744Z","steps":["trace[1165474022] 'process raft request' (duration: 399.243128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:25.82089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:25.421258Z","time spent":"399.574341ms","remote":"127.0.0.1:40746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5468,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:23:25.820912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.817647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-22T00:23:25.82092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.583594ms","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-22T00:23:25.820949Z","caller":"traceutil/trace.go:171","msg":"trace[149918274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"253.898959ms","start":"2026-04-22T00:23:25.567041Z","end":"2026-04-22T00:23:25.82094Z","steps":["trace[149918274] 'agreement among raft nodes before linearized reading' (duration: 253.779585ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:25.820966Z","caller":"traceutil/trace.go:171","msg":"trace[1626645516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:941; }","duration":"273.662697ms","start":"2026-04-22T00:23:25.547291Z","end":"2026-04-22T00:23:25.820954Z","steps":["trace[1626645516] 'agreement among raft nodes before linearized reading' (duration: 273.475149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:25.821072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.632588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:25.821138Z","caller":"traceutil/trace.go:171","msg":"trace[1540339181] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:941; }","duration":"224.740943ms","start":"2026-04-22T00:23:25.596385Z","end":"2026-04-22T00:23:25.821126Z","steps":["trace[1540339181] 'agreement among raft nodes before linearized reading' (duration: 224.577556ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:32.270198Z","caller":"traceutil/trace.go:171","msg":"trace[2094919165] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1010; }","duration":"330.410505ms","start":"2026-04-22T00:23:31.939763Z","end":"2026-04-22T00:23:32.270173Z","steps":["trace[2094919165] 'read index received' (duration: 319.082556ms)","trace[2094919165] 'applied index is now lower than readState.Index' (duration: 11.326629ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:32.270371Z","caller":"traceutil/trace.go:171","msg":"trace[353229066] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"361.480116ms","start":"2026-04-22T00:23:31.908863Z","end":"2026-04-22T00:23:32.270343Z","steps":["trace[353229066] 'process raft request' (duration: 361.185414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:32.27051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:31.908842Z","time spent":"361.595611ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:32.270296Z","caller":"traceutil/trace.go:171","msg":"trace[1758235946] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"363.312842ms","start":"2026-04-22T00:23:31.906938Z","end":"2026-04-22T00:23:32.270251Z","steps":["trace[1758235946] 'process raft request' (duration: 351.891399ms)","trace[1758235946] 'compare' (duration: 10.787206ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:32.270609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:31.906922Z","time spent":"363.630866ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:23:32.270631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.737657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:32.270815Z","caller":"traceutil/trace.go:171","msg":"trace[12522502] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:975; }","duration":"356.979638ms","start":"2026-04-22T00:23:31.913776Z","end":"2026-04-22T00:23:32.270756Z","steps":["trace[12522502] 'agreement among raft nodes before linearized reading' (duration: 356.699046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:32.270919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:31.913768Z","time spent":"357.134924ms","remote":"127.0.0.1:40866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-04-22T00:23:32.27541Z","caller":"traceutil/trace.go:171","msg":"trace[566840483] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"332.020114ms","start":"2026-04-22T00:23:31.943376Z","end":"2026-04-22T00:23:32.275396Z","steps":["trace[566840483] 'process raft request' (duration: 331.888118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:32.275503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:31.943364Z","time spent":"332.097108ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:23:32.275655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.538927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-22T00:23:32.275656Z","caller":"traceutil/trace.go:171","msg":"trace[2139607880] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"178.824711ms","start":"2026-04-22T00:23:32.096816Z","end":"2026-04-22T00:23:32.27564Z","steps":["trace[2139607880] 'process raft request' (duration: 178.546419ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:32.275694Z","caller":"traceutil/trace.go:171","msg":"trace[2144916254] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:977; }","duration":"332.613129ms","start":"2026-04-22T00:23:31.94307Z","end":"2026-04-22T00:23:32.275683Z","steps":["trace[2144916254] 'agreement among raft nodes before linearized reading' (duration: 332.524015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:32.275728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:31.943052Z","time spent":"332.668352ms","remote":"127.0.0.1:40726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-22T00:23:32.275883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.369788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T00:23:32.275963Z","caller":"traceutil/trace.go:171","msg":"trace[2077029464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:977; }","duration":"268.484152ms","start":"2026-04-22T00:23:32.007466Z","end":"2026-04-22T00:23:32.275951Z","steps":["trace[2077029464] 'agreement among raft nodes before linearized reading' (duration: 268.208971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:32.275998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.672692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T00:23:32.276063Z","caller":"traceutil/trace.go:171","msg":"trace[1331869786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:977; }","duration":"332.756816ms","start":"2026-04-22T00:23:31.943292Z","end":"2026-04-22T00:23:32.276049Z","steps":["trace[1331869786] 'agreement among raft nodes before linearized reading' (duration: 332.519595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:32.276103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:31.943286Z","time spent":"332.810007ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T00:23:35.192002Z","caller":"traceutil/trace.go:171","msg":"trace[1326159676] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"173.768299ms","start":"2026-04-22T00:23:35.018206Z","end":"2026-04-22T00:23:35.191974Z","steps":["trace[1326159676] 'process raft request' (duration: 173.55412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:51.106842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.174241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:23:51.106949Z","caller":"traceutil/trace.go:171","msg":"trace[105654857] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"575.520036ms","start":"2026-04-22T00:23:50.53141Z","end":"2026-04-22T00:23:51.10693Z","steps":["trace[105654857] 'read index received' (duration: 282.274521ms)","trace[105654857] 'applied index is now lower than readState.Index' (duration: 293.244155ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:51.107236Z","caller":"traceutil/trace.go:171","msg":"trace[1459137531] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"636.107995ms","start":"2026-04-22T00:23:50.471089Z","end":"2026-04-22T00:23:51.107197Z","steps":["trace[1459137531] 'process raft request' (duration: 342.496753ms)","trace[1459137531] 'compare' (duration: 292.889977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:51.107215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.782618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-22T00:23:51.107316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.909935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T00:23:51.107355Z","caller":"traceutil/trace.go:171","msg":"trace[876812730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1040; }","duration":"548.973008ms","start":"2026-04-22T00:23:50.55837Z","end":"2026-04-22T00:23:51.107343Z","steps":["trace[876812730] 'agreement among raft nodes before linearized reading' (duration: 548.794039ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:51.107355Z","caller":"traceutil/trace.go:171","msg":"trace[1557163066] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1040; }","duration":"575.968578ms","start":"2026-04-22T00:23:50.531367Z","end":"2026-04-22T00:23:51.107336Z","steps":["trace[1557163066] 'agreement among raft nodes before linearized reading' (duration: 575.680694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:51.107332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.642404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T00:23:51.107383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:50.471073Z","time spent":"636.250311ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:23:51.107396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:50.558358Z","time spent":"549.029651ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T00:23:51.1074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:50.531347Z","time spent":"576.043101ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T00:23:51.107417Z","caller":"traceutil/trace.go:171","msg":"trace[967041492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1040; }","duration":"366.765941ms","start":"2026-04-22T00:23:50.740631Z","end":"2026-04-22T00:23:51.107397Z","steps":["trace[967041492] 'agreement among raft nodes before linearized reading' (duration: 366.587842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:51.107462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:50.74061Z","time spent":"366.844914ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T00:23:51.10754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.204699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T00:23:51.107581Z","caller":"traceutil/trace.go:171","msg":"trace[851862114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1040; }","duration":"543.293352ms","start":"2026-04-22T00:23:50.564277Z","end":"2026-04-22T00:23:51.107571Z","steps":["trace[851862114] 'agreement among raft nodes before linearized reading' (duration: 543.201538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:51.107603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:50.564255Z","time spent":"543.342325ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T00:23:51.765586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.941191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:23:51.765879Z","caller":"traceutil/trace.go:171","msg":"trace[1119931966] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"653.768294ms","start":"2026-04-22T00:23:51.112099Z","end":"2026-04-22T00:23:51.765867Z","steps":["trace[1119931966] 'process raft request' (duration: 653.558124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:51.765922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:51.112086Z","time spent":"653.815087ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:51.765922Z","caller":"traceutil/trace.go:171","msg":"trace[891939518] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"653.796405ms","start":"2026-04-22T00:23:51.112094Z","end":"2026-04-22T00:23:51.765891Z","steps":["trace[891939518] 'process raft request' (duration: 653.619146ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:51.765961Z","caller":"traceutil/trace.go:171","msg":"trace[846708143] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"653.994465ms","start":"2026-04-22T00:23:51.111895Z","end":"2026-04-22T00:23:51.76589Z","steps":["trace[846708143] 'process raft request' (duration: 364.704838ms)","trace[846708143] 'compare' (duration: 288.605924ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:51.766055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:51.111878Z","time spent":"654.145943ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:23:51.766089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:51.112073Z","time spent":"653.922411ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:54.736315Z","caller":"traceutil/trace.go:171","msg":"trace[1379270583] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"174.564179ms","start":"2026-04-22T00:23:54.561727Z","end":"2026-04-22T00:23:54.736291Z","steps":["trace[1379270583] 'process raft request' (duration: 174.424042ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:10.269522Z","caller":"traceutil/trace.go:171","msg":"trace[1793616189] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"324.036646ms","start":"2026-04-22T00:24:09.945465Z","end":"2026-04-22T00:24:10.269502Z","steps":["trace[1793616189] 'process raft request' (duration: 279.016461ms)","trace[1793616189] 'compare' (duration: 44.676956ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:24:10.269616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:24:09.945456Z","time spent":"324.10997ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:24:14.101738Z","caller":"traceutil/trace.go:171","msg":"trace[1227881040] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"113.531542ms","start":"2026-04-22T00:24:13.988178Z","end":"2026-04-22T00:24:14.101709Z","steps":["trace[1227881040] 'process raft request' (duration: 69.716756ms)","trace[1227881040] 'compare' (duration: 43.677269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:24:16.855115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.391143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:24:16.855223Z","caller":"traceutil/trace.go:171","msg":"trace[755826668] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"309.014339ms","start":"2026-04-22T00:24:16.54619Z","end":"2026-04-22T00:24:16.855204Z","steps":["trace[755826668] 'process raft request' (duration: 113.454056ms)","trace[755826668] 'compare' (duration: 195.192533ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:24:16.855289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:24:16.546164Z","time spent":"309.095854ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:24:42.054586Z","caller":"traceutil/trace.go:171","msg":"trace[433798073] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"123.256007ms","start":"2026-04-22T00:24:41.930929Z","end":"2026-04-22T00:24:42.054185Z","steps":["trace[433798073] 'process raft request' (duration: 32.320887ms)","trace[433798073] 'compare' (duration: 90.800142ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:24:42.470387Z","caller":"traceutil/trace.go:171","msg":"trace[170676797] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"197.148085ms","start":"2026-04-22T00:24:42.273199Z","end":"2026-04-22T00:24:42.470347Z","steps":["trace[170676797] 'read index received' (duration: 136.928805ms)","trace[170676797] 'applied index is now lower than readState.Index' (duration: 60.2136ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:24:42.470529Z","caller":"traceutil/trace.go:171","msg":"trace[2141704116] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"356.932141ms","start":"2026-04-22T00:24:42.11349Z","end":"2026-04-22T00:24:42.470423Z","steps":["trace[2141704116] 'process raft request' (duration: 296.642576ms)","trace[2141704116] 'compare' (duration: 59.609104ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:24:42.470632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.40751ms","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-22T00:24:42.470812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:24:42.113468Z","time spent":"357.26602ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:24:42.47086Z","caller":"traceutil/trace.go:171","msg":"trace[50066676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1207; }","duration":"197.671366ms","start":"2026-04-22T00:24:42.273167Z","end":"2026-04-22T00:24:42.470838Z","steps":["trace[50066676] 'agreement among raft nodes before linearized reading' (duration: 197.276373ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:42.64416Z","caller":"traceutil/trace.go:171","msg":"trace[756294410] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"169.949437ms","start":"2026-04-22T00:24:42.474153Z","end":"2026-04-22T00:24:42.644102Z","steps":["trace[756294410] 'process raft request' (duration: 113.073985ms)","trace[756294410] 'compare' (duration: 56.637508ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:24:44.668725Z","caller":"traceutil/trace.go:171","msg":"trace[801551465] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"191.128208ms","start":"2026-04-22T00:24:44.477572Z","end":"2026-04-22T00:24:44.6687Z","steps":["trace[801551465] 'process raft request' (duration: 190.932386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:44.875015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.716199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:24:44.875172Z","caller":"traceutil/trace.go:171","msg":"trace[1996117133] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1265; }","duration":"116.004248ms","start":"2026-04-22T00:24:44.759152Z","end":"2026-04-22T00:24:44.875157Z","steps":["trace[1996117133] 'read index received' (duration: 48.140668ms)","trace[1996117133] 'applied index is now lower than readState.Index' (duration: 67.8627ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:24:44.875217Z","caller":"traceutil/trace.go:171","msg":"trace[1795743404] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"199.717839ms","start":"2026-04-22T00:24:44.67548Z","end":"2026-04-22T00:24:44.875198Z","steps":["trace[1795743404] 'process raft request' (duration: 64.733834ms)","trace[1795743404] 'compare' (duration: 134.545619ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:24:44.875234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.078313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:24:44.875259Z","caller":"traceutil/trace.go:171","msg":"trace[1464638120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"116.138326ms","start":"2026-04-22T00:24:44.759112Z","end":"2026-04-22T00:24:44.87525Z","steps":["trace[1464638120] 'agreement among raft nodes before linearized reading' (duration: 116.095784ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:44.875288Z","caller":"traceutil/trace.go:171","msg":"trace[1719168901] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"199.723109ms","start":"2026-04-22T00:24:44.675535Z","end":"2026-04-22T00:24:44.875258Z","steps":["trace[1719168901] 'process raft request' (duration: 199.568439ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:45.566211Z","caller":"traceutil/trace.go:171","msg":"trace[1512526570] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"376.856024ms","start":"2026-04-22T00:24:45.189328Z","end":"2026-04-22T00:24:45.566184Z","steps":["trace[1512526570] 'read index received' (duration: 340.967429ms)","trace[1512526570] 'applied index is now lower than readState.Index' (duration: 35.887495ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:24:45.56641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.062236ms","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-22T00:24:45.566454Z","caller":"traceutil/trace.go:171","msg":"trace[64321620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1216; }","duration":"377.159102ms","start":"2026-04-22T00:24:45.189285Z","end":"2026-04-22T00:24:45.566444Z","steps":["trace[64321620] 'agreement among raft nodes before linearized reading' (duration: 376.986062ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:45.566711Z","caller":"traceutil/trace.go:171","msg":"trace[1563790389] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"521.431966ms","start":"2026-04-22T00:24:45.045194Z","end":"2026-04-22T00:24:45.566626Z","steps":["trace[1563790389] 'process raft request' (duration: 485.141837ms)","trace[1563790389] 'compare' (duration: 35.743457ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:24:45.56687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:24:45.045172Z","time spent":"521.634559ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:24:45.566676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:24:45.189264Z","time spent":"377.395716ms","remote":"127.0.0.1:40830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T00:24:47.740494Z","caller":"traceutil/trace.go:171","msg":"trace[1344767225] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"128.53851ms","start":"2026-04-22T00:24:47.611923Z","end":"2026-04-22T00:24:47.740462Z","steps":["trace[1344767225] 'process raft request' (duration: 124.645017ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:58.934584Z","caller":"traceutil/trace.go:171","msg":"trace[829850240] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"131.002238ms","start":"2026-04-22T00:24:58.803555Z","end":"2026-04-22T00:24:58.934557Z","steps":["trace[829850240] 'process raft request' (duration: 130.891632ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:59.261958Z","caller":"traceutil/trace.go:171","msg":"trace[429904930] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"198.339076ms","start":"2026-04-22T00:24:59.063596Z","end":"2026-04-22T00:24:59.261935Z","steps":["trace[429904930] 'process raft request' (duration: 198.156154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:25:07.093099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.817187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T00:25:07.093202Z","caller":"traceutil/trace.go:171","msg":"trace[1710510896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1324; }","duration":"125.987438ms","start":"2026-04-22T00:25:06.967194Z","end":"2026-04-22T00:25:07.093182Z","steps":["trace[1710510896] 'range keys from in-memory index tree' (duration: 125.602003ms)"],"step_count":1}