{"level":"warn","ts":"2026-06-05T16:03:04.713587Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T16:03:04.713667Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-05T16:03:04.71374Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T16:03:04.713753Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-06-05T16:03:04.71377Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-05T16:03:04.7144Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-06-05T16:03:04.714473Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-05T16:03:04.71996Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.286847ms"} {"level":"info","ts":"2026-06-05T16:03:04.728018Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-06-05T16:03:04.728095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-06-05T16:03:04.728118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-06-05T16:03:04.728136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-05T16:03:04.728145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-06-05T16:03:04.728185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-06-05T16:03:04.739287Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-05T16:03:04.744103Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-05T16:03:04.745334Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-05T16:03:04.748454Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-05T16:03:04.748564Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-05T16:03:04.748726Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-05T16:03:04.749858Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-05T16:03:04.749948Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-05T16:03:04.750675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-06-05T16:03:04.750799Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-06-05T16:03:04.752556Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-05T16:03:04.752753Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-05T16:03:04.75278Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-05T16:03:04.752836Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-06-05T16:03:04.752848Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-06-05T16:03:05.129216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-06-05T16:03:05.129286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-06-05T16:03:05.129337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-06-05T16:03:05.129358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-06-05T16:03:05.12937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-06-05T16:03:05.129401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-06-05T16:03:05.129425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-06-05T16:03:05.130278Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T16:03:05.131216Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T16:03:05.131213Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-06-05T16:03:05.133133Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-06-05T16:03:05.13339Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T16:03:05.134971Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-05T16:03:05.135414Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-05T16:03:05.135538Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-05T16:03:05.136024Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T16:03:05.136088Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T16:03:05.13611Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T16:03:10.47258Z","caller":"traceutil/trace.go:171","msg":"trace[728046294] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"158.464232ms","start":"2026-06-05T16:03:10.314074Z","end":"2026-06-05T16:03:10.472539Z","steps":["trace[728046294] 'process raft request' (duration: 91.144218ms)","trace[728046294] 'compare' (duration: 67.177193ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:10.473109Z","caller":"traceutil/trace.go:171","msg":"trace[1927181909] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"149.031547ms","start":"2026-06-05T16:03:10.324056Z","end":"2026-06-05T16:03:10.473087Z","steps":["trace[1927181909] 'process raft request' (duration: 148.995276ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:10.473143Z","caller":"traceutil/trace.go:171","msg":"trace[980926392] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"152.585752ms","start":"2026-06-05T16:03:10.320525Z","end":"2026-06-05T16:03:10.47311Z","steps":["trace[980926392] 'process raft request' (duration: 152.443081ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:10.69346Z","caller":"traceutil/trace.go:171","msg":"trace[1551034905] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"132.638294ms","start":"2026-06-05T16:03:10.5608Z","end":"2026-06-05T16:03:10.693439Z","steps":["trace[1551034905] 'process raft request' (duration: 111.370977ms)","trace[1551034905] 'compare' (duration: 20.991365ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:10.693506Z","caller":"traceutil/trace.go:171","msg":"trace[1209662214] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"132.396992ms","start":"2026-06-05T16:03:10.5611Z","end":"2026-06-05T16:03:10.693497Z","steps":["trace[1209662214] 'process raft request' (duration: 132.182541ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:11.247084Z","caller":"traceutil/trace.go:171","msg":"trace[932893044] transaction","detail":"{read_only:false; number_of_response:0; response_revision:258; }","duration":"118.697445ms","start":"2026-06-05T16:03:11.128363Z","end":"2026-06-05T16:03:11.24706Z","steps":["trace[932893044] 'process raft request' (duration: 118.616054ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:11.247158Z","caller":"traceutil/trace.go:171","msg":"trace[1291435686] transaction","detail":"{read_only:false; number_of_response:0; response_revision:258; }","duration":"118.593034ms","start":"2026-06-05T16:03:11.128557Z","end":"2026-06-05T16:03:11.24715Z","steps":["trace[1291435686] 'process raft request' (duration: 118.482763ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:11.24708Z","caller":"traceutil/trace.go:171","msg":"trace[2052495032] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"150.199051ms","start":"2026-06-05T16:03:11.096829Z","end":"2026-06-05T16:03:11.247028Z","steps":["trace[2052495032] 'process raft request' (duration: 97.776711ms)","trace[2052495032] 'compare' (duration: 52.260899ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:11.247396Z","caller":"traceutil/trace.go:171","msg":"trace[1369244391] transaction","detail":"{read_only:false; number_of_response:0; response_revision:258; }","duration":"118.878836ms","start":"2026-06-05T16:03:11.128509Z","end":"2026-06-05T16:03:11.247388Z","steps":["trace[1369244391] 'process raft request' (duration: 118.509593ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:11.247539Z","caller":"traceutil/trace.go:171","msg":"trace[100306206] transaction","detail":"{read_only:false; number_of_response:0; response_revision:258; }","duration":"118.839426ms","start":"2026-06-05T16:03:11.12869Z","end":"2026-06-05T16:03:11.247529Z","steps":["trace[100306206] 'process raft request' (duration: 118.362763ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:11.247599Z","caller":"traceutil/trace.go:171","msg":"trace[1884280523] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:263; }","duration":"140.968837ms","start":"2026-06-05T16:03:11.106619Z","end":"2026-06-05T16:03:11.247588Z","steps":["trace[1884280523] 'read index received' (duration: 39.627191ms)","trace[1884280523] 'applied index is now lower than readState.Index' (duration: 101.340516ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:11.24775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.091968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-05T16:03:11.247832Z","caller":"traceutil/trace.go:171","msg":"trace[1674123345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:259; }","duration":"141.22076ms","start":"2026-06-05T16:03:11.106574Z","end":"2026-06-05T16:03:11.247795Z","steps":["trace[1674123345] 'agreement among raft nodes before linearized reading' (duration: 141.082659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:11.247968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.660355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3468"} {"level":"info","ts":"2026-06-05T16:03:11.248018Z","caller":"traceutil/trace.go:171","msg":"trace[1110684645] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:259; }","duration":"134.736335ms","start":"2026-06-05T16:03:11.113269Z","end":"2026-06-05T16:03:11.248005Z","steps":["trace[1110684645] 'agreement among raft nodes before linearized reading' (duration: 134.506624ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:11.621559Z","caller":"traceutil/trace.go:171","msg":"trace[421911239] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"122.958374ms","start":"2026-06-05T16:03:11.498572Z","end":"2026-06-05T16:03:11.62153Z","steps":["trace[421911239] 'process raft request' (duration: 122.848783ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:11.621799Z","caller":"traceutil/trace.go:171","msg":"trace[110379166] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"151.419739ms","start":"2026-06-05T16:03:11.47034Z","end":"2026-06-05T16:03:11.621759Z","steps":["trace[110379166] 'process raft request' (duration: 62.209957ms)","trace[110379166] 'compare' (duration: 88.739409ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:12.823366Z","caller":"traceutil/trace.go:171","msg":"trace[1061263114] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"124.970014ms","start":"2026-06-05T16:03:12.698363Z","end":"2026-06-05T16:03:12.823333Z","steps":["trace[1061263114] 'process raft request' (duration: 76.952702ms)","trace[1061263114] 'compare' (duration: 47.81214ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:13.300013Z","caller":"traceutil/trace.go:171","msg":"trace[1317154648] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"101.10765ms","start":"2026-06-05T16:03:13.198882Z","end":"2026-06-05T16:03:13.299989Z","steps":["trace[1317154648] 'process raft request' (duration: 92.364216ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:13.538494Z","caller":"traceutil/trace.go:171","msg":"trace[238126027] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:297; }","duration":"227.913456ms","start":"2026-06-05T16:03:13.310561Z","end":"2026-06-05T16:03:13.538474Z","steps":["trace[238126027] 'read index received' (duration: 59.036876ms)","trace[238126027] 'applied index is now lower than readState.Index' (duration: 168.876039ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:13.538605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.024349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-05T16:03:13.538607Z","caller":"traceutil/trace.go:171","msg":"trace[111463055] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"230.33975ms","start":"2026-06-05T16:03:13.308231Z","end":"2026-06-05T16:03:13.538571Z","steps":["trace[111463055] 'process raft request' (duration: 230.125312ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:13.538688Z","caller":"traceutil/trace.go:171","msg":"trace[1067568949] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:288; }","duration":"228.103742ms","start":"2026-06-05T16:03:13.310523Z","end":"2026-06-05T16:03:13.538626Z","steps":["trace[1067568949] 'agreement among raft nodes before linearized reading' (duration: 228.030169ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:13.761322Z","caller":"traceutil/trace.go:171","msg":"trace[1631721944] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"136.683069ms","start":"2026-06-05T16:03:13.62461Z","end":"2026-06-05T16:03:13.761293Z","steps":["trace[1631721944] 'process raft request' (duration: 123.832231ms)","trace[1631721944] 'compare' (duration: 12.728533ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:14.094872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.5059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-05T16:03:14.094956Z","caller":"traceutil/trace.go:171","msg":"trace[1481251894] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:291; }","duration":"197.679166ms","start":"2026-06-05T16:03:13.897258Z","end":"2026-06-05T16:03:14.094937Z","steps":["trace[1481251894] 'range keys from in-memory index tree' (duration: 197.397956ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:14.222241Z","caller":"traceutil/trace.go:171","msg":"trace[1194699494] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"124.303629ms","start":"2026-06-05T16:03:14.097915Z","end":"2026-06-05T16:03:14.222219Z","steps":["trace[1194699494] 'process raft request' (duration: 124.171924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:14.848731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.7876ms","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-06-05T16:03:14.848754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.687212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:14.848798Z","caller":"traceutil/trace.go:171","msg":"trace[1839930903] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:293; }","duration":"550.777205ms","start":"2026-06-05T16:03:14.298011Z","end":"2026-06-05T16:03:14.848788Z","steps":["trace[1839930903] 'range keys from in-memory index tree' (duration: 550.600409ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:14.848794Z","caller":"traceutil/trace.go:171","msg":"trace[528066045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:293; }","duration":"218.903344ms","start":"2026-06-05T16:03:14.629877Z","end":"2026-06-05T16:03:14.848781Z","steps":["trace[528066045] 'range keys from in-memory index tree' (duration: 218.694057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:14.848827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:14.297987Z","time spent":"550.833497ms","remote":"127.0.0.1:51114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" "} {"level":"info","ts":"2026-06-05T16:03:14.971415Z","caller":"traceutil/trace.go:171","msg":"trace[103670001] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"118.892272ms","start":"2026-06-05T16:03:14.852502Z","end":"2026-06-05T16:03:14.971394Z","steps":["trace[103670001] 'process raft request' (duration: 118.784898ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:15.16086Z","caller":"traceutil/trace.go:171","msg":"trace[57949745] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:304; }","duration":"263.985024ms","start":"2026-06-05T16:03:14.896843Z","end":"2026-06-05T16:03:15.160828Z","steps":["trace[57949745] 'read index received' (duration: 74.518645ms)","trace[57949745] 'applied index is now lower than readState.Index' (duration: 189.464979ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:15.161015Z","caller":"traceutil/trace.go:171","msg":"trace[1529461433] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"306.915862ms","start":"2026-06-05T16:03:14.854055Z","end":"2026-06-05T16:03:15.160971Z","steps":["trace[1529461433] 'process raft request' (duration: 244.616322ms)","trace[1529461433] 'compare' (duration: 61.99224ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:15.16125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.333476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T16:03:15.161435Z","caller":"traceutil/trace.go:171","msg":"trace[1985764978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:295; }","duration":"264.595105ms","start":"2026-06-05T16:03:14.89682Z","end":"2026-06-05T16:03:15.161415Z","steps":["trace[1985764978] 'agreement among raft nodes before linearized reading' (duration: 264.252043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:15.161968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:14.85404Z","time spent":"307.230522ms","remote":"127.0.0.1:51114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:03:15.541145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.025606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:15.541253Z","caller":"traceutil/trace.go:171","msg":"trace[757878413] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:309; }","duration":"162.657354ms","start":"2026-06-05T16:03:15.378581Z","end":"2026-06-05T16:03:15.541238Z","steps":["trace[757878413] 'read index received' (duration: 44.171273ms)","trace[757878413] 'applied index is now lower than readState.Index' (duration: 118.484891ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:15.541312Z","caller":"traceutil/trace.go:171","msg":"trace[2003967836] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"163.310006ms","start":"2026-06-05T16:03:15.377959Z","end":"2026-06-05T16:03:15.541269Z","steps":["trace[2003967836] 'process raft request' (duration: 44.808434ms)","trace[2003967836] 'compare' (duration: 117.892981ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:15.541342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.764398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-06-05T16:03:15.541593Z","caller":"traceutil/trace.go:171","msg":"trace[1624912106] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:299; }","duration":"163.038548ms","start":"2026-06-05T16:03:15.378539Z","end":"2026-06-05T16:03:15.541578Z","steps":["trace[1624912106] 'agreement among raft nodes before linearized reading' (duration: 162.740448ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:15.796179Z","caller":"traceutil/trace.go:171","msg":"trace[555654938] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"189.612401ms","start":"2026-06-05T16:03:15.606547Z","end":"2026-06-05T16:03:15.796159Z","steps":["trace[555654938] 'read index received' (duration: 189.442395ms)","trace[555654938] 'applied index is now lower than readState.Index' (duration: 169.026µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:15.796227Z","caller":"traceutil/trace.go:171","msg":"trace[1970239890] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"204.294701ms","start":"2026-06-05T16:03:15.591906Z","end":"2026-06-05T16:03:15.7962Z","steps":["trace[1970239890] 'process raft request' (duration: 204.140335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:15.796319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.763666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-06-05T16:03:15.796358Z","caller":"traceutil/trace.go:171","msg":"trace[391622083] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:301; }","duration":"189.84831ms","start":"2026-06-05T16:03:15.6065Z","end":"2026-06-05T16:03:15.796348Z","steps":["trace[391622083] 'agreement among raft nodes before linearized reading' (duration: 189.771997ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:15.796472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.219894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-06-05T16:03:15.796534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.783892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-06-05T16:03:15.796567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.328639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-05T16:03:15.796598Z","caller":"traceutil/trace.go:171","msg":"trace[1287818177] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:301; }","duration":"153.890806ms","start":"2026-06-05T16:03:15.642692Z","end":"2026-06-05T16:03:15.796583Z","steps":["trace[1287818177] 'agreement among raft nodes before linearized reading' (duration: 153.746561ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:15.796619Z","caller":"traceutil/trace.go:171","msg":"trace[208496385] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:301; }","duration":"171.408931ms","start":"2026-06-05T16:03:15.625196Z","end":"2026-06-05T16:03:15.796605Z","steps":["trace[208496385] 'agreement among raft nodes before linearized reading' (duration: 171.332719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:15.796645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.549862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-05T16:03:15.796674Z","caller":"traceutil/trace.go:171","msg":"trace[2110259291] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:301; }","duration":"140.604454ms","start":"2026-06-05T16:03:15.656063Z","end":"2026-06-05T16:03:15.796667Z","steps":["trace[2110259291] 'agreement among raft nodes before linearized reading' (duration: 140.532801ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:15.796575Z","caller":"traceutil/trace.go:171","msg":"trace[1113798529] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:301; }","duration":"141.397341ms","start":"2026-06-05T16:03:15.655161Z","end":"2026-06-05T16:03:15.796559Z","steps":["trace[1113798529] 'agreement among raft nodes before linearized reading' (duration: 141.178783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:15.796491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.074267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-06-05T16:03:15.796805Z","caller":"traceutil/trace.go:171","msg":"trace[1515968047] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:301; }","duration":"180.412178ms","start":"2026-06-05T16:03:15.616362Z","end":"2026-06-05T16:03:15.796774Z","steps":["trace[1515968047] 'agreement among raft nodes before linearized reading' (duration: 179.938212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:15.796845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.569419ms","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-06-05T16:03:15.797075Z","caller":"traceutil/trace.go:171","msg":"trace[1106188276] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:301; }","duration":"169.971313ms","start":"2026-06-05T16:03:15.62709Z","end":"2026-06-05T16:03:15.797062Z","steps":["trace[1106188276] 'agreement among raft nodes before linearized reading' (duration: 169.317951ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:15.974913Z","caller":"traceutil/trace.go:171","msg":"trace[887687106] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"164.832129ms","start":"2026-06-05T16:03:15.810058Z","end":"2026-06-05T16:03:15.974891Z","steps":["trace[887687106] 'process raft request' (duration: 163.628988ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.260014Z","caller":"traceutil/trace.go:171","msg":"trace[1683588836] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"449.572997ms","start":"2026-06-05T16:03:15.810419Z","end":"2026-06-05T16:03:16.259992Z","steps":["trace[1683588836] 'process raft request' (duration: 377.842794ms)","trace[1683588836] 'compare' (duration: 71.541307ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:16.260085Z","caller":"traceutil/trace.go:171","msg":"trace[1764023897] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"448.543783ms","start":"2026-06-05T16:03:15.811523Z","end":"2026-06-05T16:03:16.260066Z","steps":["trace[1764023897] 'process raft request' (duration: 448.423848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:16.260147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:15.810407Z","time spent":"449.699532ms","remote":"127.0.0.1:60914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T16:03:16.26019Z","caller":"traceutil/trace.go:171","msg":"trace[1635570259] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"448.47624ms","start":"2026-06-05T16:03:15.811689Z","end":"2026-06-05T16:03:16.260165Z","steps":["trace[1635570259] 'process raft request' (duration: 448.301634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:16.260269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:15.81151Z","time spent":"448.652336ms","remote":"127.0.0.1:51094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:03:16.260316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:15.811679Z","time spent":"448.578074ms","remote":"127.0.0.1:50994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T16:03:16.260293Z","caller":"traceutil/trace.go:171","msg":"trace[333497289] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:312; }","duration":"412.63222ms","start":"2026-06-05T16:03:15.847639Z","end":"2026-06-05T16:03:16.260271Z","steps":["trace[333497289] 'read index received' (duration: 126.231465ms)","trace[333497289] 'applied index is now lower than readState.Index' (duration: 286.399665ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:16.260408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.758044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-06-05T16:03:16.26046Z","caller":"traceutil/trace.go:171","msg":"trace[2129046399] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"447.617071ms","start":"2026-06-05T16:03:15.812817Z","end":"2026-06-05T16:03:16.260434Z","steps":["trace[2129046399] 'process raft request' (duration: 447.28764ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.260461Z","caller":"traceutil/trace.go:171","msg":"trace[201588306] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:311; }","duration":"412.822796ms","start":"2026-06-05T16:03:15.847627Z","end":"2026-06-05T16:03:16.260449Z","steps":["trace[201588306] 'agreement among raft nodes before linearized reading' (duration: 412.715253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:16.260532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:15.847583Z","time spent":"412.940211ms","remote":"127.0.0.1:51114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-06-05T16:03:16.260671Z","caller":"traceutil/trace.go:171","msg":"trace[1813220864] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"448.087347ms","start":"2026-06-05T16:03:15.812579Z","end":"2026-06-05T16:03:16.260666Z","steps":["trace[1813220864] 'process raft request' (duration: 447.491377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:16.260714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:15.81257Z","time spent":"448.131418ms","remote":"127.0.0.1:60906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T16:03:16.260791Z","caller":"traceutil/trace.go:171","msg":"trace[1729817360] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"446.935407ms","start":"2026-06-05T16:03:15.813843Z","end":"2026-06-05T16:03:16.260779Z","steps":["trace[1729817360] 'process raft request' (duration: 446.393329ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.260823Z","caller":"traceutil/trace.go:171","msg":"trace[267011537] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"447.341151ms","start":"2026-06-05T16:03:15.813425Z","end":"2026-06-05T16:03:16.260766Z","steps":["trace[267011537] 'process raft request' (duration: 446.766312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:16.260878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:15.813835Z","time spent":"446.991009ms","remote":"127.0.0.1:51272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:16.260556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:15.812807Z","time spent":"447.695403ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:16.260951Z","caller":"traceutil/trace.go:171","msg":"trace[1288654819] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"447.839538ms","start":"2026-06-05T16:03:15.813104Z","end":"2026-06-05T16:03:16.260944Z","steps":["trace[1288654819] 'process raft request' (duration: 447.054732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:16.260951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:15.813418Z","time spent":"447.453595ms","remote":"127.0.0.1:51272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:16.260585Z","caller":"traceutil/trace.go:171","msg":"trace[1730426261] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"448.714698ms","start":"2026-06-05T16:03:15.811862Z","end":"2026-06-05T16:03:16.260576Z","steps":["trace[1730426261] 'process raft request' (duration: 448.17815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:16.261179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:15.811853Z","time spent":"449.288208ms","remote":"127.0.0.1:51024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:03:16.26102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:15.813096Z","time spent":"447.90138ms","remote":"127.0.0.1:51272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:16.28292Z","caller":"traceutil/trace.go:171","msg":"trace[1551430896] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"304.569215ms","start":"2026-06-05T16:03:15.978334Z","end":"2026-06-05T16:03:16.282904Z","steps":["trace[1551430896] 'process raft request' (duration: 304.483552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:16.283014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:15.978315Z","time spent":"304.656748ms","remote":"127.0.0.1:51114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:03:16.509384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.834859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:16.509852Z","caller":"traceutil/trace.go:171","msg":"trace[1291434646] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"241.822814ms","start":"2026-06-05T16:03:16.267999Z","end":"2026-06-05T16:03:16.509822Z","steps":["trace[1291434646] 'process raft request' (duration: 241.625698ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.509987Z","caller":"traceutil/trace.go:171","msg":"trace[2113796050] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"244.453642ms","start":"2026-06-05T16:03:16.265518Z","end":"2026-06-05T16:03:16.509972Z","steps":["trace[2113796050] 'process raft request' (duration: 126.895478ms)","trace[2113796050] 'compare' (duration: 116.602372ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:16.51009Z","caller":"traceutil/trace.go:171","msg":"trace[1389114996] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"243.48281ms","start":"2026-06-05T16:03:16.266597Z","end":"2026-06-05T16:03:16.510079Z","steps":["trace[1389114996] 'process raft request' (duration: 242.950872ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.510196Z","caller":"traceutil/trace.go:171","msg":"trace[275300012] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"243.534052ms","start":"2026-06-05T16:03:16.26648Z","end":"2026-06-05T16:03:16.510014Z","steps":["trace[275300012] 'process raft request' (duration: 242.996984ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.510206Z","caller":"traceutil/trace.go:171","msg":"trace[156463086] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"242.131165ms","start":"2026-06-05T16:03:16.267973Z","end":"2026-06-05T16:03:16.510104Z","steps":["trace[156463086] 'process raft request' (duration: 241.607877ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.510319Z","caller":"traceutil/trace.go:171","msg":"trace[1854460001] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"241.489103ms","start":"2026-06-05T16:03:16.26882Z","end":"2026-06-05T16:03:16.510309Z","steps":["trace[1854460001] 'process raft request' (duration: 240.835461ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.510395Z","caller":"traceutil/trace.go:171","msg":"trace[1981804718] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:323; }","duration":"227.411019ms","start":"2026-06-05T16:03:16.282964Z","end":"2026-06-05T16:03:16.510375Z","steps":["trace[1981804718] 'read index received' (duration: 109.435421ms)","trace[1981804718] 'applied index is now lower than readState.Index' (duration: 117.974188ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:16.510451Z","caller":"traceutil/trace.go:171","msg":"trace[1636158410] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"241.555975ms","start":"2026-06-05T16:03:16.268883Z","end":"2026-06-05T16:03:16.510439Z","steps":["trace[1636158410] 'process raft request' (duration: 240.79903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:16.510571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.434776ms","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-06-05T16:03:16.510628Z","caller":"traceutil/trace.go:171","msg":"trace[1154768193] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:320; }","duration":"245.540699ms","start":"2026-06-05T16:03:16.265074Z","end":"2026-06-05T16:03:16.510614Z","steps":["trace[1154768193] 'agreement among raft nodes before linearized reading' (duration: 245.428335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:16.510638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.924986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4370"} {"level":"info","ts":"2026-06-05T16:03:16.510674Z","caller":"traceutil/trace.go:171","msg":"trace[1836317707] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:320; }","duration":"224.995958ms","start":"2026-06-05T16:03:16.285668Z","end":"2026-06-05T16:03:16.510664Z","steps":["trace[1836317707] 'agreement among raft nodes before linearized reading' (duration: 224.899814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:16.5107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.827612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4370"} {"level":"info","ts":"2026-06-05T16:03:16.510734Z","caller":"traceutil/trace.go:171","msg":"trace[1033253459] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:320; }","duration":"225.022139ms","start":"2026-06-05T16:03:16.285702Z","end":"2026-06-05T16:03:16.510724Z","steps":["trace[1033253459] 'agreement among raft nodes before linearized reading' (duration: 224.953336ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.509773Z","caller":"traceutil/trace.go:171","msg":"trace[140749265] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"223.030462ms","start":"2026-06-05T16:03:16.286726Z","end":"2026-06-05T16:03:16.509757Z","steps":["trace[140749265] 'process raft request' (duration: 222.995781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:16.722847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.573363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:16.723117Z","caller":"traceutil/trace.go:171","msg":"trace[1012170234] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"163.706686ms","start":"2026-06-05T16:03:16.559388Z","end":"2026-06-05T16:03:16.723095Z","steps":["trace[1012170234] 'process raft request' (duration: 62.824713ms)","trace[1012170234] 'compare' (duration: 100.376696ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:16.723835Z","caller":"traceutil/trace.go:171","msg":"trace[999467694] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"161.605195ms","start":"2026-06-05T16:03:16.562215Z","end":"2026-06-05T16:03:16.72382Z","steps":["trace[999467694] 'process raft request' (duration: 161.43077ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.723989Z","caller":"traceutil/trace.go:171","msg":"trace[196742612] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"133.736368ms","start":"2026-06-05T16:03:16.590222Z","end":"2026-06-05T16:03:16.723958Z","steps":["trace[196742612] 'process raft request' (duration: 133.545412ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.724085Z","caller":"traceutil/trace.go:171","msg":"trace[1191321225] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"133.238212ms","start":"2026-06-05T16:03:16.590832Z","end":"2026-06-05T16:03:16.724071Z","steps":["trace[1191321225] 'process raft request' (duration: 132.963223ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.724305Z","caller":"traceutil/trace.go:171","msg":"trace[1438437989] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"134.000177ms","start":"2026-06-05T16:03:16.590083Z","end":"2026-06-05T16:03:16.724083Z","steps":["trace[1438437989] 'process raft request' (duration: 133.653776ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.724365Z","caller":"traceutil/trace.go:171","msg":"trace[1470575496] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"122.921364ms","start":"2026-06-05T16:03:16.601424Z","end":"2026-06-05T16:03:16.724346Z","steps":["trace[1470575496] 'process raft request' (duration: 122.423977ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:16.724464Z","caller":"traceutil/trace.go:171","msg":"trace[1761757115] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"122.20242ms","start":"2026-06-05T16:03:16.602254Z","end":"2026-06-05T16:03:16.724456Z","steps":["trace[1761757115] 'process raft request' (duration: 121.9023ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:21.233341Z","caller":"traceutil/trace.go:171","msg":"trace[414202961] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"104.364707ms","start":"2026-06-05T16:03:21.128952Z","end":"2026-06-05T16:03:21.233317Z","steps":["trace[414202961] 'process raft request' (duration: 104.214373ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:21.483283Z","caller":"traceutil/trace.go:171","msg":"trace[644276770] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"215.978155ms","start":"2026-06-05T16:03:21.267277Z","end":"2026-06-05T16:03:21.483255Z","steps":["trace[644276770] 'read index received' (duration: 172.833474ms)","trace[644276770] 'applied index is now lower than readState.Index' (duration: 43.143291ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:21.483422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.12303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-05T16:03:21.483462Z","caller":"traceutil/trace.go:171","msg":"trace[738238676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:367; }","duration":"216.186582ms","start":"2026-06-05T16:03:21.267261Z","end":"2026-06-05T16:03:21.483447Z","steps":["trace[738238676] 'agreement among raft nodes before linearized reading' (duration: 216.09488ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:21.483617Z","caller":"traceutil/trace.go:171","msg":"trace[603380297] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"239.793103ms","start":"2026-06-05T16:03:21.243797Z","end":"2026-06-05T16:03:21.48359Z","steps":["trace[603380297] 'process raft request' (duration: 196.329281ms)","trace[603380297] 'compare' (duration: 42.7625ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:21.985671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.36652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:21.985837Z","caller":"traceutil/trace.go:171","msg":"trace[1764672275] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"381.493977ms","start":"2026-06-05T16:03:21.604268Z","end":"2026-06-05T16:03:21.985762Z","steps":["trace[1764672275] 'read index received' (duration: 146.940751ms)","trace[1764672275] 'applied index is now lower than readState.Index' (duration: 234.551286ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:21.985826Z","caller":"traceutil/trace.go:171","msg":"trace[893893858] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"401.42117ms","start":"2026-06-05T16:03:21.584362Z","end":"2026-06-05T16:03:21.985783Z","steps":["trace[893893858] 'process raft request' (duration: 166.864494ms)","trace[893893858] 'compare' (duration: 234.188895ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:21.985922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.650852ms","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-06-05T16:03:21.98595Z","caller":"traceutil/trace.go:171","msg":"trace[464335232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:369; }","duration":"381.709874ms","start":"2026-06-05T16:03:21.604231Z","end":"2026-06-05T16:03:21.985941Z","steps":["trace[464335232] 'agreement among raft nodes before linearized reading' (duration: 381.638602ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:21.98597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:21.584342Z","time spent":"401.548895ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:21.985988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:21.604217Z","time spent":"381.761075ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-05T16:03:29.17958Z","caller":"traceutil/trace.go:171","msg":"trace[353379318] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"121.892729ms","start":"2026-06-05T16:03:29.057664Z","end":"2026-06-05T16:03:29.179556Z","steps":["trace[353379318] 'process raft request' (duration: 121.702343ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:30.137472Z","caller":"traceutil/trace.go:171","msg":"trace[1450800870] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"116.158406ms","start":"2026-06-05T16:03:30.021277Z","end":"2026-06-05T16:03:30.137435Z","steps":["trace[1450800870] 'process raft request' (duration: 115.940159ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:30.296685Z","caller":"traceutil/trace.go:171","msg":"trace[806297956] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"155.343335ms","start":"2026-06-05T16:03:30.141318Z","end":"2026-06-05T16:03:30.296662Z","steps":["trace[806297956] 'process raft request' (duration: 80.53437ms)","trace[806297956] 'compare' (duration: 74.525387ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:31.845382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.85421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:31.845498Z","caller":"traceutil/trace.go:171","msg":"trace[817722843] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"205.575518ms","start":"2026-06-05T16:03:31.639895Z","end":"2026-06-05T16:03:31.845471Z","steps":["trace[817722843] 'process raft request' (duration: 48.570564ms)","trace[817722843] 'compare' (duration: 156.738367ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:31.878947Z","caller":"traceutil/trace.go:171","msg":"trace[2093786461] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:408; }","duration":"218.204678ms","start":"2026-06-05T16:03:31.66073Z","end":"2026-06-05T16:03:31.878935Z","steps":["trace[2093786461] 'read index received' (duration: 27.716369ms)","trace[2093786461] 'applied index is now lower than readState.Index' (duration: 190.487629ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:31.879064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.333592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T16:03:31.87909Z","caller":"traceutil/trace.go:171","msg":"trace[3787604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:396; }","duration":"218.397114ms","start":"2026-06-05T16:03:31.660686Z","end":"2026-06-05T16:03:31.879083Z","steps":["trace[3787604] 'agreement among raft nodes before linearized reading' (duration: 218.301891ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:31.879078Z","caller":"traceutil/trace.go:171","msg":"trace[1269636582] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"236.238242ms","start":"2026-06-05T16:03:31.642819Z","end":"2026-06-05T16:03:31.879057Z","steps":["trace[1269636582] 'process raft request' (duration: 236.058507ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:31.879093Z","caller":"traceutil/trace.go:171","msg":"trace[1117158339] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"237.710755ms","start":"2026-06-05T16:03:31.641358Z","end":"2026-06-05T16:03:31.879069Z","steps":["trace[1117158339] 'process raft request' (duration: 237.451117ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:32.207869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.140123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:32.207978Z","caller":"traceutil/trace.go:171","msg":"trace[291278457] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"322.610922ms","start":"2026-06-05T16:03:31.885354Z","end":"2026-06-05T16:03:32.207965Z","steps":["trace[291278457] 'process raft request' (duration: 177.082798ms)","trace[291278457] 'compare' (duration: 145.000199ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:32.20804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:31.885336Z","time spent":"322.678454ms","remote":"127.0.0.1:51100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:32.212678Z","caller":"traceutil/trace.go:171","msg":"trace[891400399] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"326.000978ms","start":"2026-06-05T16:03:31.886646Z","end":"2026-06-05T16:03:32.212647Z","steps":["trace[891400399] 'process raft request' (duration: 325.837663ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:32.212803Z","caller":"traceutil/trace.go:171","msg":"trace[568793220] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"325.355128ms","start":"2026-06-05T16:03:31.887423Z","end":"2026-06-05T16:03:32.212778Z","steps":["trace[568793220] 'process raft request' (duration: 325.103241ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:32.212816Z","caller":"traceutil/trace.go:171","msg":"trace[1247659663] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"324.512005ms","start":"2026-06-05T16:03:31.88828Z","end":"2026-06-05T16:03:32.212792Z","steps":["trace[1247659663] 'process raft request' (duration: 324.294928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:32.212979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:31.887412Z","time spent":"325.459812ms","remote":"127.0.0.1:60906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:32.213066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:31.888272Z","time spent":"324.71904ms","remote":"127.0.0.1:60906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:32.212711Z","caller":"traceutil/trace.go:171","msg":"trace[1338932847] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"326.504151ms","start":"2026-06-05T16:03:31.886175Z","end":"2026-06-05T16:03:32.212679Z","steps":["trace[1338932847] 'process raft request' (duration: 326.278615ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:32.212772Z","caller":"traceutil/trace.go:171","msg":"trace[1607250442] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"327.381407ms","start":"2026-06-05T16:03:31.885377Z","end":"2026-06-05T16:03:32.212758Z","steps":["trace[1607250442] 'process raft request' (duration: 326.984505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:32.213217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:31.886153Z","time spent":"327.018746ms","remote":"127.0.0.1:50994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:03:32.213279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:31.885364Z","time spent":"327.87797ms","remote":"127.0.0.1:50994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:03:32.214987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:31.886637Z","time spent":"326.205043ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:32.425118Z","caller":"traceutil/trace.go:171","msg":"trace[828130883] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"204.914777ms","start":"2026-06-05T16:03:32.220184Z","end":"2026-06-05T16:03:32.425099Z","steps":["trace[828130883] 'process raft request' (duration: 204.630359ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:32.425144Z","caller":"traceutil/trace.go:171","msg":"trace[1723364265] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"207.652064ms","start":"2026-06-05T16:03:32.217469Z","end":"2026-06-05T16:03:32.425121Z","steps":["trace[1723364265] 'process raft request' (duration: 153.129396ms)","trace[1723364265] 'compare' (duration: 53.784087ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:32.424927Z","caller":"traceutil/trace.go:171","msg":"trace[1580922095] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"207.375157ms","start":"2026-06-05T16:03:32.217527Z","end":"2026-06-05T16:03:32.424902Z","steps":["trace[1580922095] 'read index received' (duration: 42.976192ms)","trace[1580922095] 'applied index is now lower than readState.Index' (duration: 164.397834ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:32.425341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.744887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-4mbjh\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-06-05T16:03:32.425454Z","caller":"traceutil/trace.go:171","msg":"trace[2126914461] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-4mbjh; range_end:; response_count:1; response_revision:405; }","duration":"207.949662ms","start":"2026-06-05T16:03:32.217484Z","end":"2026-06-05T16:03:32.425433Z","steps":["trace[2126914461] 'agreement among raft nodes before linearized reading' (duration: 207.723666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:32.425551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.001649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"warn","ts":"2026-06-05T16:03:32.425559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.949234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-05T16:03:32.425602Z","caller":"traceutil/trace.go:171","msg":"trace[607164272] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:405; }","duration":"205.076301ms","start":"2026-06-05T16:03:32.22051Z","end":"2026-06-05T16:03:32.425586Z","steps":["trace[607164272] 'agreement among raft nodes before linearized reading' (duration: 204.986508ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:32.425615Z","caller":"traceutil/trace.go:171","msg":"trace[1172690102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"124.039836ms","start":"2026-06-05T16:03:32.30156Z","end":"2026-06-05T16:03:32.4256Z","steps":["trace[1172690102] 'agreement among raft nodes before linearized reading' (duration: 123.943293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:33.120663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.536874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:33.120936Z","caller":"traceutil/trace.go:171","msg":"trace[52493003] transaction","detail":"{read_only:false; number_of_response:1; response_revision:409; }","duration":"595.327632ms","start":"2026-06-05T16:03:32.525594Z","end":"2026-06-05T16:03:33.120921Z","steps":["trace[52493003] 'process raft request' (duration: 595.24176ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:33.120968Z","caller":"traceutil/trace.go:171","msg":"trace[1589942019] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"687.599124ms","start":"2026-06-05T16:03:32.433341Z","end":"2026-06-05T16:03:33.12094Z","steps":["trace[1589942019] 'process raft request' (duration: 687.427009ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:33.121041Z","caller":"traceutil/trace.go:171","msg":"trace[1919327012] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"691.653828ms","start":"2026-06-05T16:03:32.429365Z","end":"2026-06-05T16:03:33.121018Z","steps":["trace[1919327012] 'process raft request' (duration: 365.671021ms)","trace[1919327012] 'compare' (duration: 325.422571ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:33.121085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:32.525571Z","time spent":"595.427024ms","remote":"127.0.0.1:51100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":40,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-06-05T16:03:33.1211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:32.43333Z","time spent":"687.696716ms","remote":"127.0.0.1:60906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:33.12116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:32.429355Z","time spent":"691.74222ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:33.121327Z","caller":"traceutil/trace.go:171","msg":"trace[717123150] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:421; }","duration":"516.843118ms","start":"2026-06-05T16:03:32.604475Z","end":"2026-06-05T16:03:33.121318Z","steps":["trace[717123150] 'read index received' (duration: 190.674726ms)","trace[717123150] 'applied index is now lower than readState.Index' (duration: 326.167472ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:33.121392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.91548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:33.121419Z","caller":"traceutil/trace.go:171","msg":"trace[919696349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"516.976942ms","start":"2026-06-05T16:03:32.604436Z","end":"2026-06-05T16:03:33.121413Z","steps":["trace[919696349] 'agreement among raft nodes before linearized reading' (duration: 516.91929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:33.121443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:32.604412Z","time spent":"517.026163ms","remote":"127.0.0.1:50902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T16:03:33.1215Z","caller":"traceutil/trace.go:171","msg":"trace[2129609925] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"592.522772ms","start":"2026-06-05T16:03:32.528966Z","end":"2026-06-05T16:03:33.121489Z","steps":["trace[2129609925] 'process raft request' (duration: 591.902005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:33.121565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:32.528952Z","time spent":"592.586564ms","remote":"127.0.0.1:50994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:03:33.435727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.581887ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:33.435873Z","caller":"traceutil/trace.go:171","msg":"trace[1734126858] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:411; }","duration":"185.743032ms","start":"2026-06-05T16:03:33.250112Z","end":"2026-06-05T16:03:33.435855Z","steps":["trace[1734126858] 'range keys from in-memory index tree' (duration: 185.563707ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:33.436189Z","caller":"traceutil/trace.go:171","msg":"trace[550375019] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"308.247001ms","start":"2026-06-05T16:03:33.127925Z","end":"2026-06-05T16:03:33.436172Z","steps":["trace[550375019] 'process raft request' (duration: 213.764265ms)","trace[550375019] 'compare' (duration: 94.145076ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:33.436291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:33.12791Z","time spent":"308.331843ms","remote":"127.0.0.1:50994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T16:03:33.436492Z","caller":"traceutil/trace.go:171","msg":"trace[783930633] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"307.896081ms","start":"2026-06-05T16:03:33.128561Z","end":"2026-06-05T16:03:33.436457Z","steps":["trace[783930633] 'process raft request' (duration: 307.50268ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:33.436555Z","caller":"traceutil/trace.go:171","msg":"trace[1701973409] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"307.246013ms","start":"2026-06-05T16:03:33.12929Z","end":"2026-06-05T16:03:33.436536Z","steps":["trace[1701973409] 'process raft request' (duration: 306.839411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:33.436589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:33.128551Z","time spent":"307.993113ms","remote":"127.0.0.1:51100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T16:03:33.436658Z","caller":"traceutil/trace.go:171","msg":"trace[588210872] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:426; }","duration":"218.569768ms","start":"2026-06-05T16:03:33.218076Z","end":"2026-06-05T16:03:33.436646Z","steps":["trace[588210872] 'read index received' (duration: 123.611869ms)","trace[588210872] 'applied index is now lower than readState.Index' (duration: 94.956849ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:33.436676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:33.129283Z","time spent":"307.329825ms","remote":"127.0.0.1:60906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:33.436737Z","caller":"traceutil/trace.go:171","msg":"trace[1772699396] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"307.117229ms","start":"2026-06-05T16:03:33.129602Z","end":"2026-06-05T16:03:33.43672Z","steps":["trace[1772699396] 'process raft request' (duration: 306.858752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:33.436768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.686042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-05T16:03:33.436801Z","caller":"traceutil/trace.go:171","msg":"trace[1605193160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"218.760234ms","start":"2026-06-05T16:03:33.218032Z","end":"2026-06-05T16:03:33.436793Z","steps":["trace[1605193160] 'agreement among raft nodes before linearized reading' (duration: 218.665541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:33.436822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:33.129594Z","time spent":"307.187211ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:33.626757Z","caller":"traceutil/trace.go:171","msg":"trace[668457289] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"182.269645ms","start":"2026-06-05T16:03:33.444465Z","end":"2026-06-05T16:03:33.626735Z","steps":["trace[668457289] 'process raft request' (duration: 156.709692ms)","trace[668457289] 'compare' (duration: 25.393178ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:33.626837Z","caller":"traceutil/trace.go:171","msg":"trace[1435607134] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"182.328657ms","start":"2026-06-05T16:03:33.444494Z","end":"2026-06-05T16:03:33.626823Z","steps":["trace[1435607134] 'process raft request' (duration: 182.177652ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:33.626895Z","caller":"traceutil/trace.go:171","msg":"trace[1469781783] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"182.318317ms","start":"2026-06-05T16:03:33.444539Z","end":"2026-06-05T16:03:33.626858Z","steps":["trace[1469781783] 'process raft request' (duration: 182.173983ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:33.6271Z","caller":"traceutil/trace.go:171","msg":"trace[1521501963] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"182.537493ms","start":"2026-06-05T16:03:33.444546Z","end":"2026-06-05T16:03:33.627083Z","steps":["trace[1521501963] 'process raft request' (duration: 182.200793ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:33.62713Z","caller":"traceutil/trace.go:171","msg":"trace[469842324] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"182.300397ms","start":"2026-06-05T16:03:33.444807Z","end":"2026-06-05T16:03:33.627107Z","steps":["trace[469842324] 'process raft request' (duration: 181.972927ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:33.627424Z","caller":"traceutil/trace.go:171","msg":"trace[1911614286] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"182.386658ms","start":"2026-06-05T16:03:33.445029Z","end":"2026-06-05T16:03:33.627416Z","steps":["trace[1911614286] 'process raft request' (duration: 182.000007ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:34.466144Z","caller":"traceutil/trace.go:171","msg":"trace[1299800799] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"199.017904ms","start":"2026-06-05T16:03:34.267096Z","end":"2026-06-05T16:03:34.466114Z","steps":["trace[1299800799] 'process raft request' (duration: 166.160307ms)","trace[1299800799] 'compare' (duration: 32.749334ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:45.599543Z","caller":"traceutil/trace.go:171","msg":"trace[1876611036] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"396.267981ms","start":"2026-06-05T16:03:45.203237Z","end":"2026-06-05T16:03:45.599505Z","steps":["trace[1876611036] 'process raft request' (duration: 396.010564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:45.599733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:45.203214Z","time spent":"396.423674ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:45.875462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.299911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-05T16:03:45.875545Z","caller":"traceutil/trace.go:171","msg":"trace[662935209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:485; }","duration":"161.424254ms","start":"2026-06-05T16:03:45.714102Z","end":"2026-06-05T16:03:45.875526Z","steps":["trace[662935209] 'range keys from in-memory index tree' (duration: 161.145327ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:46.523075Z","caller":"traceutil/trace.go:171","msg":"trace[1181539988] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"306.783438ms","start":"2026-06-05T16:03:46.216261Z","end":"2026-06-05T16:03:46.523044Z","steps":["trace[1181539988] 'read index received' (duration: 303.484567ms)","trace[1181539988] 'applied index is now lower than readState.Index' (duration: 3.296861ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:46.523146Z","caller":"traceutil/trace.go:171","msg":"trace[77249974] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"319.15673ms","start":"2026-06-05T16:03:46.203947Z","end":"2026-06-05T16:03:46.523104Z","steps":["trace[77249974] 'process raft request' (duration: 315.984383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.523278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.984373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.523324Z","caller":"traceutil/trace.go:171","msg":"trace[865255027] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:531; }","duration":"307.059374ms","start":"2026-06-05T16:03:46.216253Z","end":"2026-06-05T16:03:46.523313Z","steps":["trace[865255027] 'agreement among raft nodes before linearized reading' (duration: 306.960792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.523356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:46.203913Z","time spent":"319.332915ms","remote":"127.0.0.1:50958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:46.523374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:46.216207Z","time spent":"307.152707ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-06-05T16:03:46.530048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.679796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.530138Z","caller":"traceutil/trace.go:171","msg":"trace[436475241] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:533; }","duration":"313.789589ms","start":"2026-06-05T16:03:46.216328Z","end":"2026-06-05T16:03:46.530118Z","steps":["trace[436475241] 'agreement among raft nodes before linearized reading' (duration: 313.632265ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.53018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:46.216315Z","time spent":"313.85563ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-06-05T16:03:46.530381Z","caller":"traceutil/trace.go:171","msg":"trace[119793686] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"305.831614ms","start":"2026-06-05T16:03:46.224537Z","end":"2026-06-05T16:03:46.530369Z","steps":["trace[119793686] 'process raft request' (duration: 303.775264ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.530475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:46.224511Z","time spent":"305.893046ms","remote":"127.0.0.1:50958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:46.530583Z","caller":"traceutil/trace.go:171","msg":"trace[1414588402] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"273.940215ms","start":"2026-06-05T16:03:46.256633Z","end":"2026-06-05T16:03:46.530573Z","steps":["trace[1414588402] 'process raft request' (duration: 273.180716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.530673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.720182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T16:03:46.530695Z","caller":"traceutil/trace.go:171","msg":"trace[392284504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"161.813075ms","start":"2026-06-05T16:03:46.368875Z","end":"2026-06-05T16:03:46.530688Z","steps":["trace[392284504] 'agreement among raft nodes before linearized reading' (duration: 161.763003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.53089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.106662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.530922Z","caller":"traceutil/trace.go:171","msg":"trace[1923077038] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:533; }","duration":"196.140213ms","start":"2026-06-05T16:03:46.334772Z","end":"2026-06-05T16:03:46.530913Z","steps":["trace[1923077038] 'agreement among raft nodes before linearized reading' (duration: 196.090942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.531091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.557053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.531116Z","caller":"traceutil/trace.go:171","msg":"trace[941362948] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:533; }","duration":"196.645906ms","start":"2026-06-05T16:03:46.334463Z","end":"2026-06-05T16:03:46.531109Z","steps":["trace[941362948] 'agreement among raft nodes before linearized reading' (duration: 196.572894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.53122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.799639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.531243Z","caller":"traceutil/trace.go:171","msg":"trace[1980045442] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:533; }","duration":"196.853821ms","start":"2026-06-05T16:03:46.334382Z","end":"2026-06-05T16:03:46.531236Z","steps":["trace[1980045442] 'agreement among raft nodes before linearized reading' (duration: 196.81657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.531348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.019036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.531372Z","caller":"traceutil/trace.go:171","msg":"trace[1120442831] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:533; }","duration":"222.106188ms","start":"2026-06-05T16:03:46.309258Z","end":"2026-06-05T16:03:46.531364Z","steps":["trace[1120442831] 'agreement among raft nodes before linearized reading' (duration: 222.067647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.531493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.251662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.531519Z","caller":"traceutil/trace.go:171","msg":"trace[1493896040] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:533; }","duration":"222.299963ms","start":"2026-06-05T16:03:46.309212Z","end":"2026-06-05T16:03:46.531512Z","steps":["trace[1493896040] 'agreement among raft nodes before linearized reading' (duration: 222.262232ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.531617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.480797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.531641Z","caller":"traceutil/trace.go:171","msg":"trace[1021953294] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:533; }","duration":"222.530019ms","start":"2026-06-05T16:03:46.309104Z","end":"2026-06-05T16:03:46.531634Z","steps":["trace[1021953294] 'agreement among raft nodes before linearized reading' (duration: 222.477837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.531745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.121284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.531792Z","caller":"traceutil/trace.go:171","msg":"trace[450982534] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:533; }","duration":"248.165685ms","start":"2026-06-05T16:03:46.283603Z","end":"2026-06-05T16:03:46.531769Z","steps":["trace[450982534] 'agreement among raft nodes before linearized reading' (duration: 248.119624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.531984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.588502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T16:03:46.532051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.555279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.532074Z","caller":"traceutil/trace.go:171","msg":"trace[1102284005] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:533; }","duration":"315.717556ms","start":"2026-06-05T16:03:46.216337Z","end":"2026-06-05T16:03:46.532054Z","steps":["trace[1102284005] 'agreement among raft nodes before linearized reading' (duration: 315.522201ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:46.532104Z","caller":"traceutil/trace.go:171","msg":"trace[462361761] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:533; }","duration":"249.61619ms","start":"2026-06-05T16:03:46.282477Z","end":"2026-06-05T16:03:46.532093Z","steps":["trace[462361761] 'agreement among raft nodes before linearized reading' (duration: 249.563269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.532129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:46.216333Z","time spent":"315.781087ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-06-05T16:03:46.532258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.168944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.532277Z","caller":"traceutil/trace.go:171","msg":"trace[1097171162] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:533; }","duration":"250.208015ms","start":"2026-06-05T16:03:46.282063Z","end":"2026-06-05T16:03:46.532271Z","steps":["trace[1097171162] 'agreement among raft nodes before linearized reading' (duration: 250.175875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.532392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.701231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.532407Z","caller":"traceutil/trace.go:171","msg":"trace[1894437721] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:533; }","duration":"269.751233ms","start":"2026-06-05T16:03:46.262651Z","end":"2026-06-05T16:03:46.532402Z","steps":["trace[1894437721] 'agreement among raft nodes before linearized reading' (duration: 269.693641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.532414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.899356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.532468Z","caller":"traceutil/trace.go:171","msg":"trace[1508556918] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:533; }","duration":"269.976178ms","start":"2026-06-05T16:03:46.262477Z","end":"2026-06-05T16:03:46.532454Z","steps":["trace[1508556918] 'agreement among raft nodes before linearized reading' (duration: 269.888106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.532513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.655762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.532546Z","caller":"traceutil/trace.go:171","msg":"trace[1064835913] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:533; }","duration":"292.751405ms","start":"2026-06-05T16:03:46.239786Z","end":"2026-06-05T16:03:46.532538Z","steps":["trace[1064835913] 'agreement among raft nodes before linearized reading' (duration: 292.695333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.532013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.418104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T16:03:46.532687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.794295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.532688Z","caller":"traceutil/trace.go:171","msg":"trace[1659508157] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:533; }","duration":"270.143612ms","start":"2026-06-05T16:03:46.262533Z","end":"2026-06-05T16:03:46.532676Z","steps":["trace[1659508157] 'agreement among raft nodes before linearized reading' (duration: 269.437055ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:46.532713Z","caller":"traceutil/trace.go:171","msg":"trace[129795094] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:533; }","duration":"292.836897ms","start":"2026-06-05T16:03:46.239869Z","end":"2026-06-05T16:03:46.532706Z","steps":["trace[129795094] 'agreement among raft nodes before linearized reading' (duration: 292.796696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:46.533733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.895474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:46.533758Z","caller":"traceutil/trace.go:171","msg":"trace[1949089509] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:533; }","duration":"293.063712ms","start":"2026-06-05T16:03:46.240686Z","end":"2026-06-05T16:03:46.533749Z","steps":["trace[1949089509] 'agreement among raft nodes before linearized reading' (duration: 291.913474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:47.625134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.928136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:47.625214Z","caller":"traceutil/trace.go:171","msg":"trace[76519668] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.014776023s","start":"2026-06-05T16:03:46.610423Z","end":"2026-06-05T16:03:47.625199Z","steps":["trace[76519668] 'process raft request' (duration: 548.725483ms)","trace[76519668] 'compare' (duration: 465.779213ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:47.62525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:46.610396Z","time spent":"1.014839543s","remote":"127.0.0.1:50958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T16:03:47.626653Z","caller":"traceutil/trace.go:171","msg":"trace[1200324278] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.009549704s","start":"2026-06-05T16:03:46.617075Z","end":"2026-06-05T16:03:47.626625Z","steps":["trace[1200324278] 'process raft request' (duration: 1.009240017s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:47.626862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:46.617052Z","time spent":"1.009708138s","remote":"127.0.0.1:50958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:47.626861Z","caller":"traceutil/trace.go:171","msg":"trace[398911394] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"984.349049ms","start":"2026-06-05T16:03:46.642479Z","end":"2026-06-05T16:03:47.626828Z","steps":["trace[398911394] 'process raft request' (duration: 984.077692ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:47.626968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:46.642456Z","time spent":"984.454492ms","remote":"127.0.0.1:50958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:50.709368Z","caller":"traceutil/trace.go:171","msg":"trace[1724354197] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:598; }","duration":"118.308361ms","start":"2026-06-05T16:03:50.591033Z","end":"2026-06-05T16:03:50.709341Z","steps":["trace[1724354197] 'read index received' (duration: 2.757555ms)","trace[1724354197] 'applied index is now lower than readState.Index' (duration: 115.549776ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:50.709471Z","caller":"traceutil/trace.go:171","msg":"trace[1241280995] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"120.969054ms","start":"2026-06-05T16:03:50.588474Z","end":"2026-06-05T16:03:50.709443Z","steps":["trace[1241280995] 'process raft request' (duration: 92.774592ms)","trace[1241280995] 'compare' (duration: 27.959027ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:50.709565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.783674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:50.709605Z","caller":"traceutil/trace.go:171","msg":"trace[1189628582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"107.857236ms","start":"2026-06-05T16:03:50.601735Z","end":"2026-06-05T16:03:50.709593Z","steps":["trace[1189628582] 'agreement among raft nodes before linearized reading' (duration: 107.777554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:50.70962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.562817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T16:03:50.709689Z","caller":"traceutil/trace.go:171","msg":"trace[462503987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"118.65873ms","start":"2026-06-05T16:03:50.591015Z","end":"2026-06-05T16:03:50.709673Z","steps":["trace[462503987] 'agreement among raft nodes before linearized reading' (duration: 118.448255ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:51.542825Z","caller":"traceutil/trace.go:171","msg":"trace[1922236525] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"148.473888ms","start":"2026-06-05T16:03:51.394335Z","end":"2026-06-05T16:03:51.542808Z","steps":["trace[1922236525] 'process raft request' (duration: 148.095209ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:52.226672Z","caller":"traceutil/trace.go:171","msg":"trace[28004025] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"193.098874ms","start":"2026-06-05T16:03:52.033541Z","end":"2026-06-05T16:03:52.22664Z","steps":["trace[28004025] 'process raft request' (duration: 192.444059ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:53.379297Z","caller":"traceutil/trace.go:171","msg":"trace[799375908] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"159.914764ms","start":"2026-06-05T16:03:53.219351Z","end":"2026-06-05T16:03:53.379266Z","steps":["trace[799375908] 'process raft request' (duration: 159.407482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:53.785529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.528895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:53.785648Z","caller":"traceutil/trace.go:171","msg":"trace[1440975562] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"352.826922ms","start":"2026-06-05T16:03:53.432797Z","end":"2026-06-05T16:03:53.785623Z","steps":["trace[1440975562] 'process raft request' (duration: 214.124033ms)","trace[1440975562] 'compare' (duration: 138.360901ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:53.785723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:53.432772Z","time spent":"352.917083ms","remote":"127.0.0.1:51278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T16:03:54.624909Z","caller":"traceutil/trace.go:171","msg":"trace[1669204195] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"395.185016ms","start":"2026-06-05T16:03:54.229695Z","end":"2026-06-05T16:03:54.62488Z","steps":["trace[1669204195] 'process raft request' (duration: 394.323606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:54.625076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:54.229666Z","time spent":"395.328469ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:55.717971Z","caller":"traceutil/trace.go:171","msg":"trace[427113897] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"487.969704ms","start":"2026-06-05T16:03:55.229978Z","end":"2026-06-05T16:03:55.717948Z","steps":["trace[427113897] 'read index received' (duration: 487.273669ms)","trace[427113897] 'applied index is now lower than readState.Index' (duration: 693.075µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:55.718024Z","caller":"traceutil/trace.go:171","msg":"trace[2042984574] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"604.704562ms","start":"2026-06-05T16:03:55.113277Z","end":"2026-06-05T16:03:55.717982Z","steps":["trace[2042984574] 'process raft request' (duration: 604.132719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.718264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.255931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mslvp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-05T16:03:55.718421Z","caller":"traceutil/trace.go:171","msg":"trace[17440861] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mslvp; range_end:; response_count:1; response_revision:602; }","duration":"488.452595ms","start":"2026-06-05T16:03:55.229947Z","end":"2026-06-05T16:03:55.7184Z","steps":["trace[17440861] 'agreement among raft nodes before linearized reading' (duration: 488.110728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.71848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:55.229934Z","time spent":"488.530837ms","remote":"127.0.0.1:51100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-mslvp\" "} {"level":"warn","ts":"2026-06-05T16:03:55.71829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.364985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:03:55.718635Z","caller":"traceutil/trace.go:171","msg":"trace[1499827614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"114.773514ms","start":"2026-06-05T16:03:55.603846Z","end":"2026-06-05T16:03:55.71862Z","steps":["trace[1499827614] 'agreement among raft nodes before linearized reading' (duration: 114.380736ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:55.71908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:55.113255Z","time spent":"605.092131ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:03:56.315179Z","caller":"traceutil/trace.go:171","msg":"trace[2064786290] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"582.016899ms","start":"2026-06-05T16:03:55.733128Z","end":"2026-06-05T16:03:56.315145Z","steps":["trace[2064786290] 'process raft request' (duration: 581.854995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:56.315471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:55.733105Z","time spent":"582.230594ms","remote":"127.0.0.1:51100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:58.02212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239800109s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:58.022237Z","caller":"traceutil/trace.go:171","msg":"trace[1279689359] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"2.184649908s","start":"2026-06-05T16:03:55.837558Z","end":"2026-06-05T16:03:58.022208Z","steps":["trace[1279689359] 'read index received' (duration: 477.422843ms)","trace[1279689359] 'applied index is now lower than readState.Index' (duration: 1.707226225s)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:03:58.022365Z","caller":"traceutil/trace.go:171","msg":"trace[1695339535] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"2.240589142s","start":"2026-06-05T16:03:55.781767Z","end":"2026-06-05T16:03:58.022356Z","steps":["trace[1695339535] 'process raft request' (duration: 1.000506828s)","trace[1695339535] 'compare' (duration: 1.239683686s)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:58.022425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:55.781736Z","time spent":"2.240670334s","remote":"127.0.0.1:51160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:03:58.023268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.185713011s","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-06-05T16:03:58.023297Z","caller":"traceutil/trace.go:171","msg":"trace[1496516787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"2.185782943s","start":"2026-06-05T16:03:55.837506Z","end":"2026-06-05T16:03:58.023289Z","steps":["trace[1496516787] 'agreement among raft nodes before linearized reading' (duration: 2.185063917s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:58.023319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:55.837484Z","time spent":"2.185829254s","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-05T16:03:58.522625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410878183480634,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T16:03:59.090304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.952057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:03:59.090432Z","caller":"traceutil/trace.go:171","msg":"trace[1365541544] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"1.068161668s","start":"2026-06-05T16:03:58.022259Z","end":"2026-06-05T16:03:59.09042Z","steps":["trace[1365541544] 'read index received' (duration: 614.023296ms)","trace[1365541544] 'applied index is now lower than readState.Index' (duration: 454.137522ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:59.090526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.490180386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T16:03:59.090575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.332491871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T16:03:59.090635Z","caller":"traceutil/trace.go:171","msg":"trace[1437616184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"2.332593603s","start":"2026-06-05T16:03:56.758034Z","end":"2026-06-05T16:03:59.090628Z","steps":["trace[1437616184] 'agreement among raft nodes before linearized reading' (duration: 2.33244814s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:59.090659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:56.757991Z","time spent":"2.332661105s","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-05T16:03:59.090657Z","caller":"traceutil/trace.go:171","msg":"trace[258595688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"1.490257248s","start":"2026-06-05T16:03:57.600307Z","end":"2026-06-05T16:03:59.090564Z","steps":["trace[258595688] 'agreement among raft nodes before linearized reading' (duration: 1.490164746s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:59.090702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:57.600286Z","time spent":"1.490405831s","remote":"127.0.0.1:50902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T16:03:59.09087Z","caller":"traceutil/trace.go:171","msg":"trace[282468667] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.139001684s","start":"2026-06-05T16:03:57.951855Z","end":"2026-06-05T16:03:59.090856Z","steps":["trace[282468667] 'process raft request' (duration: 684.415363ms)","trace[282468667] 'compare' (duration: 453.739403ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:03:59.09094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:57.951831Z","time spent":"1.139070785s","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:59.090941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064754093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-06-05T16:03:59.091014Z","caller":"traceutil/trace.go:171","msg":"trace[1988241041] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:606; }","duration":"1.064864365s","start":"2026-06-05T16:03:58.026142Z","end":"2026-06-05T16:03:59.091006Z","steps":["trace[1988241041] 'agreement among raft nodes before linearized reading' (duration: 1.064767083s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:59.091037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:58.026123Z","time spent":"1.064907046s","remote":"127.0.0.1:51114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-06-05T16:03:59.091063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998082041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-05T16:03:59.091083Z","caller":"traceutil/trace.go:171","msg":"trace[863074406] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.063767401s","start":"2026-06-05T16:03:58.02728Z","end":"2026-06-05T16:03:59.091048Z","steps":["trace[863074406] 'process raft request' (duration: 1.063098127s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:59.091115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.44402784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T16:03:59.091163Z","caller":"traceutil/trace.go:171","msg":"trace[1492044028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"2.444116481s","start":"2026-06-05T16:03:56.647031Z","end":"2026-06-05T16:03:59.091148Z","steps":["trace[1492044028] 'agreement among raft nodes before linearized reading' (duration: 2.443991859s)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:03:59.091152Z","caller":"traceutil/trace.go:171","msg":"trace[1945569415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"2.998225324s","start":"2026-06-05T16:03:56.092903Z","end":"2026-06-05T16:03:59.091128Z","steps":["trace[1945569415] 'agreement among raft nodes before linearized reading' (duration: 2.997908817s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:59.091194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:56.647005Z","time spent":"2.444183112s","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T16:03:59.091206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:56.092874Z","time spent":"2.998320567s","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-05T16:03:59.091206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.253035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-05T16:03:59.091246Z","caller":"traceutil/trace.go:171","msg":"trace[146508469] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:606; }","duration":"774.294595ms","start":"2026-06-05T16:03:58.316941Z","end":"2026-06-05T16:03:59.091236Z","steps":["trace[146508469] 'agreement among raft nodes before linearized reading' (duration: 774.190133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:03:59.091246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:58.027258Z","time spent":"1.063898705s","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:03:59.091274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:03:58.316883Z","time spent":"774.384348ms","remote":"127.0.0.1:50938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-06-05T16:04:00.719518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.618403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:04:00.719669Z","caller":"traceutil/trace.go:171","msg":"trace[1212988922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"119.757047ms","start":"2026-06-05T16:04:00.599835Z","end":"2026-06-05T16:04:00.719592Z","steps":["trace[1212988922] 'range keys from in-memory index tree' (duration: 119.553972ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:11.687049Z","caller":"traceutil/trace.go:171","msg":"trace[1298482168] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"173.877547ms","start":"2026-06-05T16:04:11.513138Z","end":"2026-06-05T16:04:11.687016Z","steps":["trace[1298482168] 'process raft request' (duration: 173.610371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:15.359467Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.028127609s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-05T16:04:15.490014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.156324ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-05T16:04:15.491895Z","caller":"traceutil/trace.go:171","msg":"trace[387934536] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"1.061012354s","start":"2026-06-05T16:04:14.430862Z","end":"2026-06-05T16:04:15.491874Z","steps":["trace[387934536] 'process raft request' (duration: 1.06079955s)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:15.49189Z","caller":"traceutil/trace.go:171","msg":"trace[412286357] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:793; }","duration":"497.092045ms","start":"2026-06-05T16:04:14.99477Z","end":"2026-06-05T16:04:15.491862Z","steps":["trace[412286357] 'read index received' (duration: 364.991104ms)","trace[412286357] 'applied index is now lower than readState.Index' (duration: 132.099731ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:04:15.492037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.248157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T16:04:15.492136Z","caller":"traceutil/trace.go:171","msg":"trace[174628407] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:765; }","duration":"497.412811ms","start":"2026-06-05T16:04:14.994713Z","end":"2026-06-05T16:04:15.492126Z","steps":["trace[174628407] 'agreement among raft nodes before linearized reading' (duration: 497.227947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:15.492118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:14.43083Z","time spent":"1.061154797s","remote":"127.0.0.1:51160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:15.492183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:14.994691Z","time spent":"497.479491ms","remote":"127.0.0.1:60822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:04:15.492311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.289422ms","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-06-05T16:04:15.492652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.564318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-05T16:04:15.492681Z","caller":"traceutil/trace.go:171","msg":"trace[244115982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"230.697611ms","start":"2026-06-05T16:04:15.261963Z","end":"2026-06-05T16:04:15.492661Z","steps":["trace[244115982] 'agreement among raft nodes before linearized reading' (duration: 230.196861ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:15.492718Z","caller":"traceutil/trace.go:171","msg":"trace[1620699986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"230.648739ms","start":"2026-06-05T16:04:15.262053Z","end":"2026-06-05T16:04:15.492702Z","steps":["trace[1620699986] 'agreement among raft nodes before linearized reading' (duration: 230.258122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:15.492521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.369063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-05T16:04:15.492959Z","caller":"traceutil/trace.go:171","msg":"trace[1330558896] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"231.799701ms","start":"2026-06-05T16:04:15.261132Z","end":"2026-06-05T16:04:15.492932Z","steps":["trace[1330558896] 'agreement among raft nodes before linearized reading' (duration: 231.067757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:15.492527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.770554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-05T16:04:15.493054Z","caller":"traceutil/trace.go:171","msg":"trace[1466267133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"272.315534ms","start":"2026-06-05T16:04:15.220703Z","end":"2026-06-05T16:04:15.493019Z","steps":["trace[1466267133] 'agreement among raft nodes before linearized reading' (duration: 271.52934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:15.493242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.867527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-05T16:04:15.493329Z","caller":"traceutil/trace.go:171","msg":"trace[1763144519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"322.955309ms","start":"2026-06-05T16:04:15.17036Z","end":"2026-06-05T16:04:15.493315Z","steps":["trace[1763144519] 'agreement among raft nodes before linearized reading' (duration: 322.590792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:15.493739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:15.170304Z","time spent":"323.417838ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-05T16:04:19.516256Z","caller":"traceutil/trace.go:171","msg":"trace[2020095238] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"132.340237ms","start":"2026-06-05T16:04:19.383896Z","end":"2026-06-05T16:04:19.516236Z","steps":["trace[2020095238] 'process raft request' (duration: 132.190664ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:19.590408Z","caller":"traceutil/trace.go:171","msg":"trace[1213617751] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"106.965914ms","start":"2026-06-05T16:04:19.483414Z","end":"2026-06-05T16:04:19.59038Z","steps":["trace[1213617751] 'process raft request' (duration: 87.221693ms)","trace[1213617751] 'compare' (duration: 19.611128ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:19.795901Z","caller":"traceutil/trace.go:171","msg":"trace[1766231741] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"129.683148ms","start":"2026-06-05T16:04:19.666194Z","end":"2026-06-05T16:04:19.795877Z","steps":["trace[1766231741] 'process raft request' (duration: 129.367193ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:19.796393Z","caller":"traceutil/trace.go:171","msg":"trace[1692557707] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"128.394556ms","start":"2026-06-05T16:04:19.667982Z","end":"2026-06-05T16:04:19.796376Z","steps":["trace[1692557707] 'process raft request' (duration: 128.327214ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:19.985613Z","caller":"traceutil/trace.go:171","msg":"trace[881744040] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"112.042947ms","start":"2026-06-05T16:04:19.873541Z","end":"2026-06-05T16:04:19.985584Z","steps":["trace[881744040] 'process raft request' (duration: 45.006582ms)","trace[881744040] 'compare' (duration: 66.813521ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:19.990863Z","caller":"traceutil/trace.go:171","msg":"trace[264487030] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"116.895346ms","start":"2026-06-05T16:04:19.873937Z","end":"2026-06-05T16:04:19.990832Z","steps":["trace[264487030] 'process raft request' (duration: 116.779534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:20.551789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.755903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:04:20.551942Z","caller":"traceutil/trace.go:171","msg":"trace[1947281943] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"186.99317ms","start":"2026-06-05T16:04:20.364899Z","end":"2026-06-05T16:04:20.551892Z","steps":["trace[1947281943] 'process raft request' (duration: 15.057253ms)","trace[1947281943] 'compare' (duration: 171.654762ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:20.55198Z","caller":"traceutil/trace.go:171","msg":"trace[443024114] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"179.663536ms","start":"2026-06-05T16:04:20.372308Z","end":"2026-06-05T16:04:20.551971Z","steps":["trace[443024114] 'process raft request' (duration: 179.630666ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:20.552443Z","caller":"traceutil/trace.go:171","msg":"trace[338686275] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"183.352224ms","start":"2026-06-05T16:04:20.369023Z","end":"2026-06-05T16:04:20.552375Z","steps":["trace[338686275] 'process raft request' (duration: 182.868525ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:20.552463Z","caller":"traceutil/trace.go:171","msg":"trace[184087968] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"186.861197ms","start":"2026-06-05T16:04:20.365588Z","end":"2026-06-05T16:04:20.552449Z","steps":["trace[184087968] 'read index received' (duration: 14.35131ms)","trace[184087968] 'applied index is now lower than readState.Index' (duration: 172.508767ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:04:20.552595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.989629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-zgsjn\" ","response":"range_response_count:1 size:4495"} {"level":"info","ts":"2026-06-05T16:04:20.552637Z","caller":"traceutil/trace.go:171","msg":"trace[699634272] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-zgsjn; range_end:; response_count:1; response_revision:818; }","duration":"187.066851ms","start":"2026-06-05T16:04:20.365556Z","end":"2026-06-05T16:04:20.552623Z","steps":["trace[699634272] 'agreement among raft nodes before linearized reading' (duration: 186.935619ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:20.742659Z","caller":"traceutil/trace.go:171","msg":"trace[640351037] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"179.462973ms","start":"2026-06-05T16:04:20.563168Z","end":"2026-06-05T16:04:20.742631Z","steps":["trace[640351037] 'process raft request' (duration: 121.161866ms)","trace[640351037] 'compare' (duration: 58.170735ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:20.81671Z","caller":"traceutil/trace.go:171","msg":"trace[822930699] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"251.05347ms","start":"2026-06-05T16:04:20.565626Z","end":"2026-06-05T16:04:20.81668Z","steps":["trace[822930699] 'process raft request' (duration: 250.699884ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:20.819252Z","caller":"traceutil/trace.go:171","msg":"trace[680159232] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:849; }","duration":"216.627257ms","start":"2026-06-05T16:04:20.602606Z","end":"2026-06-05T16:04:20.819233Z","steps":["trace[680159232] 'read index received' (duration: 81.766812ms)","trace[680159232] 'applied index is now lower than readState.Index' (duration: 134.859595ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:20.819357Z","caller":"traceutil/trace.go:171","msg":"trace[178962019] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"203.04932ms","start":"2026-06-05T16:04:20.616271Z","end":"2026-06-05T16:04:20.81932Z","steps":["trace[178962019] 'process raft request' (duration: 201.115115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:20.819452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.782839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T16:04:20.819776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.894556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-05T16:04:20.819885Z","caller":"traceutil/trace.go:171","msg":"trace[1464433737] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:821; }","duration":"161.059779ms","start":"2026-06-05T16:04:20.658807Z","end":"2026-06-05T16:04:20.819867Z","steps":["trace[1464433737] 'agreement among raft nodes before linearized reading' (duration: 160.774384ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:20.819958Z","caller":"traceutil/trace.go:171","msg":"trace[2139810619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:821; }","duration":"217.383251ms","start":"2026-06-05T16:04:20.602555Z","end":"2026-06-05T16:04:20.819938Z","steps":["trace[2139810619] 'agreement among raft nodes before linearized reading' (duration: 216.78935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:21.285716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.476164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050272"} {"level":"info","ts":"2026-06-05T16:04:21.285824Z","caller":"traceutil/trace.go:171","msg":"trace[1205501167] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:821; }","duration":"355.572926ms","start":"2026-06-05T16:04:20.930204Z","end":"2026-06-05T16:04:21.285777Z","steps":["trace[1205501167] 'range keys from in-memory index tree' (duration: 355.123527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:21.285866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:20.930149Z","time spent":"355.707478ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050294,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-06-05T16:04:21.632397Z","caller":"traceutil/trace.go:171","msg":"trace[1860459152] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"135.68419ms","start":"2026-06-05T16:04:21.496686Z","end":"2026-06-05T16:04:21.63237Z","steps":["trace[1860459152] 'process raft request' (duration: 135.346484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:22.139766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.842009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:04:22.139972Z","caller":"traceutil/trace.go:171","msg":"trace[438855136] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"503.481477ms","start":"2026-06-05T16:04:21.636467Z","end":"2026-06-05T16:04:22.139948Z","steps":["trace[438855136] 'process raft request' (duration: 106.364453ms)","trace[438855136] 'compare' (duration: 396.356271ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:04:22.140054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:21.636441Z","time spent":"503.57562ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:22.140172Z","caller":"traceutil/trace.go:171","msg":"trace[1138009377] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"474.964105ms","start":"2026-06-05T16:04:21.665188Z","end":"2026-06-05T16:04:22.140152Z","steps":["trace[1138009377] 'read index received' (duration: 77.525715ms)","trace[1138009377] 'applied index is now lower than readState.Index' (duration: 397.43676ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:04:22.140375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.169739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T16:04:22.140429Z","caller":"traceutil/trace.go:171","msg":"trace[564249300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:823; }","duration":"475.25887ms","start":"2026-06-05T16:04:21.665155Z","end":"2026-06-05T16:04:22.140414Z","steps":["trace[564249300] 'agreement among raft nodes before linearized reading' (duration: 475.095337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:22.140452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:21.665137Z","time spent":"475.31129ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-05T16:04:22.140647Z","caller":"traceutil/trace.go:171","msg":"trace[871297369] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"389.995796ms","start":"2026-06-05T16:04:21.750645Z","end":"2026-06-05T16:04:22.140641Z","steps":["trace[871297369] 'process raft request' (duration: 389.267213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:22.140589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.25605ms","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-06-05T16:04:22.140662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.562478ms","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-06-05T16:04:22.140698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:21.750618Z","time spent":"390.046147ms","remote":"127.0.0.1:56440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1737,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T16:04:22.140693Z","caller":"traceutil/trace.go:171","msg":"trace[13711239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:824; }","duration":"475.365841ms","start":"2026-06-05T16:04:21.665308Z","end":"2026-06-05T16:04:22.140674Z","steps":["trace[13711239] 'agreement among raft nodes before linearized reading' (duration: 475.164248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:22.140668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.493986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T16:04:22.14071Z","caller":"traceutil/trace.go:171","msg":"trace[1588897892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:824; }","duration":"474.634659ms","start":"2026-06-05T16:04:21.666062Z","end":"2026-06-05T16:04:22.140697Z","steps":["trace[1588897892] 'agreement among raft nodes before linearized reading' (duration: 474.475806ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:22.140739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:21.665303Z","time spent":"475.421362ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-05T16:04:22.140744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:21.666057Z","time spent":"474.67891ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-05T16:04:22.140748Z","caller":"traceutil/trace.go:171","msg":"trace[594096002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:824; }","duration":"474.594488ms","start":"2026-06-05T16:04:21.666133Z","end":"2026-06-05T16:04:22.140727Z","steps":["trace[594096002] 'agreement among raft nodes before linearized reading' (duration: 474.348654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:22.140798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:21.666127Z","time spent":"474.660399ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-05T16:04:34.415543Z","caller":"traceutil/trace.go:171","msg":"trace[290209175] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"126.227655ms","start":"2026-06-05T16:04:34.289294Z","end":"2026-06-05T16:04:34.415522Z","steps":["trace[290209175] 'read index received' (duration: 126.051902ms)","trace[290209175] 'applied index is now lower than readState.Index' (duration: 174.783µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:34.415648Z","caller":"traceutil/trace.go:171","msg":"trace[770940277] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"168.932324ms","start":"2026-06-05T16:04:34.24665Z","end":"2026-06-05T16:04:34.415582Z","steps":["trace[770940277] 'process raft request' (duration: 168.732341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:34.415881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.3526ms","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-06-05T16:04:34.415914Z","caller":"traceutil/trace.go:171","msg":"trace[215219956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:956; }","duration":"120.40716ms","start":"2026-06-05T16:04:34.295498Z","end":"2026-06-05T16:04:34.415905Z","steps":["trace[215219956] 'agreement among raft nodes before linearized reading' (duration: 120.281888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:34.415967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.569549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T16:04:34.41604Z","caller":"traceutil/trace.go:171","msg":"trace[187204542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:956; }","duration":"121.680591ms","start":"2026-06-05T16:04:34.294342Z","end":"2026-06-05T16:04:34.416023Z","steps":["trace[187204542] 'agreement among raft nodes before linearized reading' (duration: 121.434427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:34.416276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.47256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T16:04:34.416303Z","caller":"traceutil/trace.go:171","msg":"trace[1296201138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:956; }","duration":"127.041519ms","start":"2026-06-05T16:04:34.289253Z","end":"2026-06-05T16:04:34.416295Z","steps":["trace[1296201138] 'agreement among raft nodes before linearized reading' (duration: 126.378908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:34.416593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.481264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-05T16:04:34.416638Z","caller":"traceutil/trace.go:171","msg":"trace[1064032431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:956; }","duration":"122.549505ms","start":"2026-06-05T16:04:34.294077Z","end":"2026-06-05T16:04:34.416627Z","steps":["trace[1064032431] 'agreement among raft nodes before linearized reading' (duration: 121.64658ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:34.907377Z","caller":"traceutil/trace.go:171","msg":"trace[673865158] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"487.614768ms","start":"2026-06-05T16:04:34.419738Z","end":"2026-06-05T16:04:34.907353Z","steps":["trace[673865158] 'process raft request' (duration: 481.61969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:34.90752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:34.419716Z","time spent":"487.73682ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:34.925399Z","caller":"traceutil/trace.go:171","msg":"trace[27992682] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"505.359349ms","start":"2026-06-05T16:04:34.420021Z","end":"2026-06-05T16:04:34.925381Z","steps":["trace[27992682] 'process raft request' (duration: 505.213856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:34.92553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:34.420008Z","time spent":"505.451881ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:34.925612Z","caller":"traceutil/trace.go:171","msg":"trace[631889801] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"505.526292ms","start":"2026-06-05T16:04:34.420068Z","end":"2026-06-05T16:04:34.925594Z","steps":["trace[631889801] 'process raft request' (duration: 505.276808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:34.925724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:34.420047Z","time spent":"505.618803ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:34.925874Z","caller":"traceutil/trace.go:171","msg":"trace[492176080] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:991; }","duration":"456.068772ms","start":"2026-06-05T16:04:34.46979Z","end":"2026-06-05T16:04:34.925859Z","steps":["trace[492176080] 'read index received' (duration: 431.51813ms)","trace[492176080] 'applied index is now lower than readState.Index' (duration: 24.549792ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:34.925913Z","caller":"traceutil/trace.go:171","msg":"trace[887936588] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"505.566133ms","start":"2026-06-05T16:04:34.420318Z","end":"2026-06-05T16:04:34.925884Z","steps":["trace[887936588] 'process raft request' (duration: 505.215767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:34.926044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.242535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T16:04:34.926079Z","caller":"traceutil/trace.go:171","msg":"trace[118061732] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:961; }","duration":"456.320287ms","start":"2026-06-05T16:04:34.469749Z","end":"2026-06-05T16:04:34.926069Z","steps":["trace[118061732] 'agreement among raft nodes before linearized reading' (duration: 456.234055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:34.926116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:34.469722Z","time spent":"456.380647ms","remote":"127.0.0.1:51100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:04:34.926343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:34.420309Z","time spent":"505.959909ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:34.927097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.525174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:04:34.927168Z","caller":"traceutil/trace.go:171","msg":"trace[980065104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"320.616546ms","start":"2026-06-05T16:04:34.60653Z","end":"2026-06-05T16:04:34.927147Z","steps":["trace[980065104] 'agreement among raft nodes before linearized reading' (duration: 320.521825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:34.927236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:34.606499Z","time spent":"320.721108ms","remote":"127.0.0.1:50902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T16:04:36.896083Z","caller":"traceutil/trace.go:171","msg":"trace[1457387675] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"416.683135ms","start":"2026-06-05T16:04:36.479382Z","end":"2026-06-05T16:04:36.896065Z","steps":["trace[1457387675] 'read index received' (duration: 416.550672ms)","trace[1457387675] 'applied index is now lower than readState.Index' (duration: 131.843µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:36.896135Z","caller":"traceutil/trace.go:171","msg":"trace[452732971] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"470.640285ms","start":"2026-06-05T16:04:36.42548Z","end":"2026-06-05T16:04:36.89612Z","steps":["trace[452732971] 'process raft request' (duration: 470.438572ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:36.896206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.843686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T16:04:36.896236Z","caller":"traceutil/trace.go:171","msg":"trace[1651923387] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:962; }","duration":"416.934158ms","start":"2026-06-05T16:04:36.479291Z","end":"2026-06-05T16:04:36.896225Z","steps":["trace[1651923387] 'agreement among raft nodes before linearized reading' (duration: 416.869977ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:36.896263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.479249Z","time spent":"417.00449ms","remote":"127.0.0.1:60938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:04:36.896267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.425454Z","time spent":"470.716446ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:36.896598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.225432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:04:36.896694Z","caller":"traceutil/trace.go:171","msg":"trace[597546955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"293.371294ms","start":"2026-06-05T16:04:36.603306Z","end":"2026-06-05T16:04:36.896677Z","steps":["trace[597546955] 'agreement among raft nodes before linearized reading' (duration: 292.936557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:37.431222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410878183481363,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T16:04:37.615641Z","caller":"traceutil/trace.go:171","msg":"trace[1706408691] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"700.205205ms","start":"2026-06-05T16:04:36.915413Z","end":"2026-06-05T16:04:37.615619Z","steps":["trace[1706408691] 'process raft request' (duration: 700.079863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:37.615885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.91539Z","time spent":"700.387059ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:37.681079Z","caller":"traceutil/trace.go:171","msg":"trace[1032870291] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:997; }","duration":"750.617932ms","start":"2026-06-05T16:04:36.930415Z","end":"2026-06-05T16:04:37.681033Z","steps":["trace[1032870291] 'read index received' (duration: 685.070431ms)","trace[1032870291] 'applied index is now lower than readState.Index' (duration: 65.546251ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:37.681107Z","caller":"traceutil/trace.go:171","msg":"trace[663774395] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"749.545145ms","start":"2026-06-05T16:04:36.931541Z","end":"2026-06-05T16:04:37.681086Z","steps":["trace[663774395] 'process raft request' (duration: 749.355312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:37.6813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.798815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T16:04:37.681403Z","caller":"traceutil/trace.go:171","msg":"trace[1867445218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:964; }","duration":"750.874956ms","start":"2026-06-05T16:04:36.930468Z","end":"2026-06-05T16:04:37.681342Z","steps":["trace[1867445218] 'agreement among raft nodes before linearized reading' (duration: 750.747874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:37.681454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.930462Z","time spent":"750.979208ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T16:04:37.681533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.713364ms","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-06-05T16:04:37.6813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.863566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-05T16:04:37.681595Z","caller":"traceutil/trace.go:171","msg":"trace[544672606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:964; }","duration":"750.814685ms","start":"2026-06-05T16:04:36.930767Z","end":"2026-06-05T16:04:37.681581Z","steps":["trace[544672606] 'agreement among raft nodes before linearized reading' (duration: 750.620482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:37.681626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.930758Z","time spent":"750.863416ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T16:04:37.681673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.931525Z","time spent":"749.764468ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:37.681621Z","caller":"traceutil/trace.go:171","msg":"trace[1064095522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:964; }","duration":"751.194951ms","start":"2026-06-05T16:04:36.930409Z","end":"2026-06-05T16:04:37.681604Z","steps":["trace[1064095522] 'agreement among raft nodes before linearized reading' (duration: 750.744504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:37.681861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:36.930361Z","time spent":"751.464876ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-05T16:04:37.814105Z","caller":"traceutil/trace.go:171","msg":"trace[1146937061] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"127.975642ms","start":"2026-06-05T16:04:37.686084Z","end":"2026-06-05T16:04:37.81406Z","steps":["trace[1146937061] 'process raft request' (duration: 48.678181ms)","trace[1146937061] 'compare' (duration: 79.171639ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:37.814193Z","caller":"traceutil/trace.go:171","msg":"trace[1369051366] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"127.982932ms","start":"2026-06-05T16:04:37.686195Z","end":"2026-06-05T16:04:37.814178Z","steps":["trace[1369051366] 'process raft request' (duration: 127.956462ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:37.814195Z","caller":"traceutil/trace.go:171","msg":"trace[109020440] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"128.051533ms","start":"2026-06-05T16:04:37.686125Z","end":"2026-06-05T16:04:37.814177Z","steps":["trace[109020440] 'process raft request' (duration: 127.916811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:38.90976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.383326ms","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-06-05T16:04:38.909819Z","caller":"traceutil/trace.go:171","msg":"trace[921434233] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:968; }","duration":"108.489388ms","start":"2026-06-05T16:04:38.801317Z","end":"2026-06-05T16:04:38.909807Z","steps":["trace[921434233] 'count revisions from in-memory index tree' (duration: 108.265375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:38.909844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.389264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-05T16:04:38.909923Z","caller":"traceutil/trace.go:171","msg":"trace[166313112] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:968; }","duration":"273.523857ms","start":"2026-06-05T16:04:38.63638Z","end":"2026-06-05T16:04:38.909903Z","steps":["trace[166313112] 'range keys from in-memory index tree' (duration: 273.284403ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:39.489265Z","caller":"traceutil/trace.go:171","msg":"trace[1965257559] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"575.435844ms","start":"2026-06-05T16:04:38.913805Z","end":"2026-06-05T16:04:39.489241Z","steps":["trace[1965257559] 'process raft request' (duration: 575.22894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.489421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:38.913787Z","time spent":"575.558676ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:39.834421Z","caller":"traceutil/trace.go:171","msg":"trace[1432401980] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"231.189587ms","start":"2026-06-05T16:04:39.603211Z","end":"2026-06-05T16:04:39.834401Z","steps":["trace[1432401980] 'read index received' (duration: 231.118196ms)","trace[1432401980] 'applied index is now lower than readState.Index' (duration: 70.391µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:39.834539Z","caller":"traceutil/trace.go:171","msg":"trace[1568544368] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"275.699482ms","start":"2026-06-05T16:04:39.558815Z","end":"2026-06-05T16:04:39.834514Z","steps":["trace[1568544368] 'process raft request' (duration: 275.419578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.834722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.501462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:04:39.834796Z","caller":"traceutil/trace.go:171","msg":"trace[732397183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"231.625564ms","start":"2026-06-05T16:04:39.603159Z","end":"2026-06-05T16:04:39.834785Z","steps":["trace[732397183] 'agreement among raft nodes before linearized reading' (duration: 231.412331ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:39.862681Z","caller":"traceutil/trace.go:171","msg":"trace[1082837570] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"175.429655ms","start":"2026-06-05T16:04:39.687232Z","end":"2026-06-05T16:04:39.862662Z","steps":["trace[1082837570] 'process raft request' (duration: 175.0876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:39.862864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.471106ms","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-06-05T16:04:39.862836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.372796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T16:04:39.862924Z","caller":"traceutil/trace.go:171","msg":"trace[1714192268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:971; }","duration":"242.576638ms","start":"2026-06-05T16:04:39.620332Z","end":"2026-06-05T16:04:39.862909Z","steps":["trace[1714192268] 'agreement among raft nodes before linearized reading' (duration: 242.407176ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:04:39.862973Z","caller":"traceutil/trace.go:171","msg":"trace[1402688884] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:971; }","duration":"232.571839ms","start":"2026-06-05T16:04:39.630348Z","end":"2026-06-05T16:04:39.862919Z","steps":["trace[1402688884] 'agreement among raft nodes before linearized reading' (duration: 232.379146ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:40.044016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.029435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:04:40.044126Z","caller":"traceutil/trace.go:171","msg":"trace[763908569] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"176.995776ms","start":"2026-06-05T16:04:39.867112Z","end":"2026-06-05T16:04:40.044108Z","steps":["trace[763908569] 'process raft request' (duration: 25.781398ms)","trace[763908569] 'compare' (duration: 150.879623ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:04:40.428857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410878183481389,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T16:04:40.63764Z","caller":"traceutil/trace.go:171","msg":"trace[1241084759] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1008; }","duration":"709.226113ms","start":"2026-06-05T16:04:39.928392Z","end":"2026-06-05T16:04:40.637619Z","steps":["trace[1241084759] 'read index received' (duration: 638.554303ms)","trace[1241084759] 'applied index is now lower than readState.Index' (duration: 70.67076ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:04:40.637751Z","caller":"traceutil/trace.go:171","msg":"trace[1589880032] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"770.244229ms","start":"2026-06-05T16:04:39.867496Z","end":"2026-06-05T16:04:40.63774Z","steps":["trace[1589880032] 'process raft request' (duration: 699.439966ms)","trace[1589880032] 'compare' (duration: 70.510799ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:04:40.637861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:39.867479Z","time spent":"770.30256ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:04:40.637999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.57344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T16:04:40.638078Z","caller":"traceutil/trace.go:171","msg":"trace[1414065140] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:975; }","duration":"709.675491ms","start":"2026-06-05T16:04:39.928386Z","end":"2026-06-05T16:04:40.638061Z","steps":["trace[1414065140] 'agreement among raft nodes before linearized reading' (duration: 709.467028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:40.638063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.455326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T16:04:40.638122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:39.928329Z","time spent":"709.781712ms","remote":"127.0.0.1:60940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":99,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-06-05T16:04:40.638126Z","caller":"traceutil/trace.go:171","msg":"trace[1537840303] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:975; }","duration":"509.570817ms","start":"2026-06-05T16:04:40.128539Z","end":"2026-06-05T16:04:40.63811Z","steps":["trace[1537840303] 'agreement among raft nodes before linearized reading' (duration: 509.466135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:40.638164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:40.128515Z","time spent":"509.639849ms","remote":"127.0.0.1:51008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-06-05T16:04:40.638345Z","caller":"traceutil/trace.go:171","msg":"trace[1243271721] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"770.747018ms","start":"2026-06-05T16:04:39.867586Z","end":"2026-06-05T16:04:40.638333Z","steps":["trace[1243271721] 'process raft request' (duration: 769.957955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:40.638431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:39.867565Z","time spent":"770.810418ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:40.638458Z","caller":"traceutil/trace.go:171","msg":"trace[518211699] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"770.663596ms","start":"2026-06-05T16:04:39.867783Z","end":"2026-06-05T16:04:40.638447Z","steps":["trace[518211699] 'process raft request' (duration: 769.799003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:04:40.638554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:04:39.867761Z","time spent":"770.723007ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:04:41.598715Z","caller":"traceutil/trace.go:171","msg":"trace[823487979] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"101.123529ms","start":"2026-06-05T16:04:41.497567Z","end":"2026-06-05T16:04:41.598691Z","steps":["trace[823487979] 'process raft request' (duration: 100.983227ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:07.008837Z","caller":"traceutil/trace.go:171","msg":"trace[1825462359] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"114.410948ms","start":"2026-06-05T16:05:06.894388Z","end":"2026-06-05T16:05:07.0088Z","steps":["trace[1825462359] 'process raft request' (duration: 109.386461ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:07.008994Z","caller":"traceutil/trace.go:171","msg":"trace[221421927] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"112.461661ms","start":"2026-06-05T16:05:06.896185Z","end":"2026-06-05T16:05:07.008646Z","steps":["trace[221421927] 'read index received' (duration: 107.687427ms)","trace[221421927] 'applied index is now lower than readState.Index' (duration: 4.773024ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:07.009024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.804015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T16:05:07.009115Z","caller":"traceutil/trace.go:171","msg":"trace[1703594149] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1064; }","duration":"112.935378ms","start":"2026-06-05T16:05:06.896156Z","end":"2026-06-05T16:05:07.009092Z","steps":["trace[1703594149] 'agreement among raft nodes before linearized reading' (duration: 112.738185ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:07.033881Z","caller":"traceutil/trace.go:171","msg":"trace[273784160] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"136.946581ms","start":"2026-06-05T16:05:06.89691Z","end":"2026-06-05T16:05:07.033857Z","steps":["trace[273784160] 'process raft request' (duration: 136.814459ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:21.177832Z","caller":"traceutil/trace.go:171","msg":"trace[1010268350] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1154; }","duration":"112.906795ms","start":"2026-06-05T16:05:21.064906Z","end":"2026-06-05T16:05:21.177813Z","steps":["trace[1010268350] 'read index received' (duration: 112.791804ms)","trace[1010268350] 'applied index is now lower than readState.Index' (duration: 114.101µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:05:21.177902Z","caller":"traceutil/trace.go:171","msg":"trace[531867584] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"207.898896ms","start":"2026-06-05T16:05:20.969969Z","end":"2026-06-05T16:05:21.177868Z","steps":["trace[531867584] 'process raft request' (duration: 207.717814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:21.178041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.109478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-05T16:05:21.178103Z","caller":"traceutil/trace.go:171","msg":"trace[251948450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1110; }","duration":"113.207739ms","start":"2026-06-05T16:05:21.064879Z","end":"2026-06-05T16:05:21.178087Z","steps":["trace[251948450] 'agreement among raft nodes before linearized reading' (duration: 113.004786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:21.432633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.456286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:05:21.432739Z","caller":"traceutil/trace.go:171","msg":"trace[1566774252] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"250.029876ms","start":"2026-06-05T16:05:21.18269Z","end":"2026-06-05T16:05:21.432719Z","steps":["trace[1566774252] 'process raft request' (duration: 85.413878ms)","trace[1566774252] 'compare' (duration: 164.360285ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:22.834777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.587309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T16:05:22.834872Z","caller":"traceutil/trace.go:171","msg":"trace[111507423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1114; }","duration":"526.719391ms","start":"2026-06-05T16:05:22.308128Z","end":"2026-06-05T16:05:22.834848Z","steps":["trace[111507423] 'range keys from in-memory index tree' (duration: 526.421236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:22.834962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:22.308107Z","time spent":"526.833242ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T16:05:22.835312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.15463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:05:22.835353Z","caller":"traceutil/trace.go:171","msg":"trace[1326702321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"233.227812ms","start":"2026-06-05T16:05:22.602115Z","end":"2026-06-05T16:05:22.835343Z","steps":["trace[1326702321] 'range keys from in-memory index tree' (duration: 233.083349ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:35.606136Z","caller":"traceutil/trace.go:171","msg":"trace[206651203] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"165.216544ms","start":"2026-06-05T16:05:35.440885Z","end":"2026-06-05T16:05:35.606102Z","steps":["trace[206651203] 'read index received' (duration: 164.91453ms)","trace[206651203] 'applied index is now lower than readState.Index' (duration: 300.564µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:35.606475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.555918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-05T16:05:35.606544Z","caller":"traceutil/trace.go:171","msg":"trace[172035942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1156; }","duration":"165.70573ms","start":"2026-06-05T16:05:35.440819Z","end":"2026-06-05T16:05:35.606525Z","steps":["trace[172035942] 'agreement among raft nodes before linearized reading' (duration: 165.437287ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:35.606945Z","caller":"traceutil/trace.go:171","msg":"trace[90555945] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"212.349147ms","start":"2026-06-05T16:05:35.394578Z","end":"2026-06-05T16:05:35.606927Z","steps":["trace[90555945] 'process raft request' (duration: 211.326654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:35.77562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.479874ms","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-06-05T16:05:35.775724Z","caller":"traceutil/trace.go:171","msg":"trace[1145143341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1157; }","duration":"117.637845ms","start":"2026-06-05T16:05:35.658062Z","end":"2026-06-05T16:05:35.775699Z","steps":["trace[1145143341] 'range keys from in-memory index tree' (duration: 117.279551ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:36.006847Z","caller":"traceutil/trace.go:171","msg":"trace[1150599110] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"226.523936ms","start":"2026-06-05T16:05:35.78028Z","end":"2026-06-05T16:05:36.006804Z","steps":["trace[1150599110] 'process raft request' (duration: 226.236853ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:37.872972Z","caller":"traceutil/trace.go:171","msg":"trace[1349892901] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1209; }","duration":"253.011621ms","start":"2026-06-05T16:05:37.619936Z","end":"2026-06-05T16:05:37.872948Z","steps":["trace[1349892901] 'read index received' (duration: 252.800699ms)","trace[1349892901] 'applied index is now lower than readState.Index' (duration: 210.012µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:37.873225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.258654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-05T16:05:37.873716Z","caller":"traceutil/trace.go:171","msg":"trace[1384472024] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"256.569754ms","start":"2026-06-05T16:05:37.617112Z","end":"2026-06-05T16:05:37.873682Z","steps":["trace[1384472024] 'process raft request' (duration: 255.691534ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:37.873312Z","caller":"traceutil/trace.go:171","msg":"trace[1306079607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1162; }","duration":"253.360645ms","start":"2026-06-05T16:05:37.619913Z","end":"2026-06-05T16:05:37.873274Z","steps":["trace[1306079607] 'agreement among raft nodes before linearized reading' (duration: 253.132823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:38.487591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.301271ms","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-06-05T16:05:38.487662Z","caller":"traceutil/trace.go:171","msg":"trace[466101558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1163; }","duration":"476.382342ms","start":"2026-06-05T16:05:38.011265Z","end":"2026-06-05T16:05:38.487647Z","steps":["trace[466101558] 'range keys from in-memory index tree' (duration: 476.150259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:38.48769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:38.011213Z","time spent":"476.470873ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T16:05:38.488022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.213267ms","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-06-05T16:05:38.488269Z","caller":"traceutil/trace.go:171","msg":"trace[1402407183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1163; }","duration":"469.472779ms","start":"2026-06-05T16:05:38.018756Z","end":"2026-06-05T16:05:38.488228Z","steps":["trace[1402407183] 'range keys from in-memory index tree' (duration: 468.859392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:38.488334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:38.018745Z","time spent":"469.576931ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-05T16:05:38.503915Z","caller":"traceutil/trace.go:171","msg":"trace[2051486846] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"180.11297ms","start":"2026-06-05T16:05:38.323766Z","end":"2026-06-05T16:05:38.503879Z","steps":["trace[2051486846] 'read index received' (duration: 179.776116ms)","trace[2051486846] 'applied index is now lower than readState.Index' (duration: 335.214µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:05:38.50402Z","caller":"traceutil/trace.go:171","msg":"trace[1614145239] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"210.852933ms","start":"2026-06-05T16:05:38.29312Z","end":"2026-06-05T16:05:38.503973Z","steps":["trace[1614145239] 'process raft request' (duration: 210.480418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:38.50417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.394073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-05T16:05:38.504242Z","caller":"traceutil/trace.go:171","msg":"trace[1172780788] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:1164; }","duration":"180.503354ms","start":"2026-06-05T16:05:38.32372Z","end":"2026-06-05T16:05:38.504223Z","steps":["trace[1172780788] 'agreement among raft nodes before linearized reading' (duration: 180.321391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:38.801336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.133428ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-06-05T16:05:39.169549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.374444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:05:39.169696Z","caller":"traceutil/trace.go:171","msg":"trace[332317623] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"367.310945ms","start":"2026-06-05T16:05:38.802352Z","end":"2026-06-05T16:05:39.169663Z","steps":["trace[332317623] 'process raft request' (duration: 209.745739ms)","trace[332317623] 'compare' (duration: 157.236602ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:39.169835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:38.802326Z","time spent":"367.415136ms","remote":"127.0.0.1:50938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:05:39.559603Z","caller":"traceutil/trace.go:171","msg":"trace[1570075633] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1216; }","duration":"385.477262ms","start":"2026-06-05T16:05:39.174098Z","end":"2026-06-05T16:05:39.559575Z","steps":["trace[1570075633] 'read index received' (duration: 383.200875ms)","trace[1570075633] 'applied index is now lower than readState.Index' (duration: 2.275227ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:39.559867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.748315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-05T16:05:39.559903Z","caller":"traceutil/trace.go:171","msg":"trace[1720651491] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1168; }","duration":"385.836515ms","start":"2026-06-05T16:05:39.174055Z","end":"2026-06-05T16:05:39.559892Z","steps":["trace[1720651491] 'agreement among raft nodes before linearized reading' (duration: 385.616093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:39.559944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:39.174044Z","time spent":"385.890576ms","remote":"127.0.0.1:50938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-06-05T16:05:39.55993Z","caller":"traceutil/trace.go:171","msg":"trace[394346358] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"386.218459ms","start":"2026-06-05T16:05:39.173688Z","end":"2026-06-05T16:05:39.559906Z","steps":["trace[394346358] 'process raft request' (duration: 383.72603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:39.560076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:39.173665Z","time spent":"386.337382ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:05:39.992026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.174825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T16:05:39.992099Z","caller":"traceutil/trace.go:171","msg":"trace[731711768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1168; }","duration":"114.294696ms","start":"2026-06-05T16:05:39.877787Z","end":"2026-06-05T16:05:39.992082Z","steps":["trace[731711768] 'range keys from in-memory index tree' (duration: 113.599948ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:40.834355Z","caller":"traceutil/trace.go:171","msg":"trace[1510609787] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1219; }","duration":"245.679954ms","start":"2026-06-05T16:05:40.588649Z","end":"2026-06-05T16:05:40.834329Z","steps":["trace[1510609787] 'read index received' (duration: 245.399731ms)","trace[1510609787] 'applied index is now lower than readState.Index' (duration: 279.373µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:05:40.834432Z","caller":"traceutil/trace.go:171","msg":"trace[137902059] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"325.523092ms","start":"2026-06-05T16:05:40.508851Z","end":"2026-06-05T16:05:40.834374Z","steps":["trace[137902059] 'process raft request' (duration: 325.34517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:40.834544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.876226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-05T16:05:40.834577Z","caller":"traceutil/trace.go:171","msg":"trace[1062921790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1171; }","duration":"245.959198ms","start":"2026-06-05T16:05:40.588609Z","end":"2026-06-05T16:05:40.834568Z","steps":["trace[1062921790] 'agreement among raft nodes before linearized reading' (duration: 245.839887ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:40.834603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:40.508815Z","time spent":"325.697714ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:05:40.834655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.496482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-05T16:05:40.834637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.921102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:05:40.834712Z","caller":"traceutil/trace.go:171","msg":"trace[525760215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1171; }","duration":"245.588904ms","start":"2026-06-05T16:05:40.589105Z","end":"2026-06-05T16:05:40.834694Z","steps":["trace[525760215] 'agreement among raft nodes before linearized reading' (duration: 245.406371ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:40.834727Z","caller":"traceutil/trace.go:171","msg":"trace[1495275097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1171; }","duration":"232.062545ms","start":"2026-06-05T16:05:40.602645Z","end":"2026-06-05T16:05:40.834708Z","steps":["trace[1495275097] 'agreement among raft nodes before linearized reading' (duration: 231.901793ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:41.198928Z","caller":"traceutil/trace.go:171","msg":"trace[624285576] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"359.154178ms","start":"2026-06-05T16:05:40.839742Z","end":"2026-06-05T16:05:41.198897Z","steps":["trace[624285576] 'process raft request' (duration: 344.337604ms)","trace[624285576] 'compare' (duration: 14.426649ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:41.199092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:40.839725Z","time spent":"359.279289ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:05:41.204029Z","caller":"traceutil/trace.go:171","msg":"trace[1052856620] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1220; }","duration":"122.841007ms","start":"2026-06-05T16:05:41.081172Z","end":"2026-06-05T16:05:41.204013Z","steps":["trace[1052856620] 'read index received' (duration: 102.890793ms)","trace[1052856620] 'applied index is now lower than readState.Index' (duration: 19.949544ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:41.204116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.925768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:05:41.204144Z","caller":"traceutil/trace.go:171","msg":"trace[49613638] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1173; }","duration":"122.992348ms","start":"2026-06-05T16:05:41.081145Z","end":"2026-06-05T16:05:41.204137Z","steps":["trace[49613638] 'agreement among raft nodes before linearized reading' (duration: 122.929938ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:41.20418Z","caller":"traceutil/trace.go:171","msg":"trace[780743562] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"364.37493ms","start":"2026-06-05T16:05:40.839786Z","end":"2026-06-05T16:05:41.204161Z","steps":["trace[780743562] 'process raft request' (duration: 364.138347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:41.204279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:40.839769Z","time spent":"364.46176ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:05:41.485332Z","caller":"traceutil/trace.go:171","msg":"trace[270259982] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1222; }","duration":"152.272281ms","start":"2026-06-05T16:05:41.333041Z","end":"2026-06-05T16:05:41.485313Z","steps":["trace[270259982] 'read index received' (duration: 152.258081ms)","trace[270259982] 'applied index is now lower than readState.Index' (duration: 13.7µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:41.48541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.354283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:05:41.485428Z","caller":"traceutil/trace.go:171","msg":"trace[485089584] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1174; }","duration":"152.418463ms","start":"2026-06-05T16:05:41.333003Z","end":"2026-06-05T16:05:41.485422Z","steps":["trace[485089584] 'agreement among raft nodes before linearized reading' (duration: 152.371853ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:41.485539Z","caller":"traceutil/trace.go:171","msg":"trace[598101897] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"205.424424ms","start":"2026-06-05T16:05:41.280109Z","end":"2026-06-05T16:05:41.485533Z","steps":["trace[598101897] 'process raft request' (duration: 205.1122ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:43.307113Z","caller":"traceutil/trace.go:171","msg":"trace[1711846142] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"100.423168ms","start":"2026-06-05T16:05:43.206641Z","end":"2026-06-05T16:05:43.307064Z","steps":["trace[1711846142] 'process raft request' (duration: 100.089524ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:48.408234Z","caller":"traceutil/trace.go:171","msg":"trace[1159387455] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"300.822701ms","start":"2026-06-05T16:05:48.107386Z","end":"2026-06-05T16:05:48.408209Z","steps":["trace[1159387455] 'process raft request' (duration: 300.602418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:48.408395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:48.107353Z","time spent":"300.963692ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:05:48.434048Z","caller":"traceutil/trace.go:171","msg":"trace[756534074] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1244; }","duration":"257.10432ms","start":"2026-06-05T16:05:48.176915Z","end":"2026-06-05T16:05:48.434019Z","steps":["trace[756534074] 'read index received' (duration: 231.186774ms)","trace[756534074] 'applied index is now lower than readState.Index' (duration: 25.916096ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:48.434212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.322983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T16:05:48.434287Z","caller":"traceutil/trace.go:171","msg":"trace[441207753] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1196; }","duration":"257.471735ms","start":"2026-06-05T16:05:48.176806Z","end":"2026-06-05T16:05:48.434278Z","steps":["trace[441207753] 'agreement among raft nodes before linearized reading' (duration: 257.332324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:48.434335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.948188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-05T16:05:48.434399Z","caller":"traceutil/trace.go:171","msg":"trace[1421966027] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:1196; }","duration":"110.06326ms","start":"2026-06-05T16:05:48.32432Z","end":"2026-06-05T16:05:48.434383Z","steps":["trace[1421966027] 'agreement among raft nodes before linearized reading' (duration: 109.868387ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:48.434114Z","caller":"traceutil/trace.go:171","msg":"trace[516360027] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"323.007384ms","start":"2026-06-05T16:05:48.111076Z","end":"2026-06-05T16:05:48.434083Z","steps":["trace[516360027] 'process raft request' (duration: 322.725201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:48.434841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:48.111038Z","time spent":"323.675962ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:05:49.845459Z","caller":"traceutil/trace.go:171","msg":"trace[1049458583] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"193.951863ms","start":"2026-06-05T16:05:49.651482Z","end":"2026-06-05T16:05:49.845434Z","steps":["trace[1049458583] 'process raft request' (duration: 193.777101ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:50.611008Z","caller":"traceutil/trace.go:171","msg":"trace[900379319] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1252; }","duration":"171.950075ms","start":"2026-06-05T16:05:50.439035Z","end":"2026-06-05T16:05:50.610985Z","steps":["trace[900379319] 'read index received' (duration: 171.752353ms)","trace[900379319] 'applied index is now lower than readState.Index' (duration: 196.842µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:05:50.611067Z","caller":"traceutil/trace.go:171","msg":"trace[670356256] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"197.011591ms","start":"2026-06-05T16:05:50.414023Z","end":"2026-06-05T16:05:50.611035Z","steps":["trace[670356256] 'process raft request' (duration: 196.813228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:50.611256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.206048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-05T16:05:50.611296Z","caller":"traceutil/trace.go:171","msg":"trace[945076586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"172.267239ms","start":"2026-06-05T16:05:50.439019Z","end":"2026-06-05T16:05:50.611286Z","steps":["trace[945076586] 'agreement among raft nodes before linearized reading' (duration: 172.101727ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:50.73357Z","caller":"traceutil/trace.go:171","msg":"trace[1436724531] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"118.558448ms","start":"2026-06-05T16:05:50.614987Z","end":"2026-06-05T16:05:50.733545Z","steps":["trace[1436724531] 'process raft request' (duration: 118.401206ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:51.110111Z","caller":"traceutil/trace.go:171","msg":"trace[2041491355] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"169.733017ms","start":"2026-06-05T16:05:50.940357Z","end":"2026-06-05T16:05:51.11009Z","steps":["trace[2041491355] 'process raft request' (duration: 169.544045ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:53.428718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.486292ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:05:53.428855Z","caller":"traceutil/trace.go:171","msg":"trace[1062441922] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1211; }","duration":"178.612223ms","start":"2026-06-05T16:05:53.250213Z","end":"2026-06-05T16:05:53.428826Z","steps":["trace[1062441922] 'range keys from in-memory index tree' (duration: 178.477442ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:53.971555Z","caller":"traceutil/trace.go:171","msg":"trace[2069628165] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"107.218419ms","start":"2026-06-05T16:05:53.864311Z","end":"2026-06-05T16:05:53.97153Z","steps":["trace[2069628165] 'process raft request' (duration: 107.032597ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:54.384265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.862187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:05:54.384473Z","caller":"traceutil/trace.go:171","msg":"trace[917017048] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"508.762103ms","start":"2026-06-05T16:05:53.875689Z","end":"2026-06-05T16:05:54.384451Z","steps":["trace[917017048] 'process raft request' (duration: 275.637993ms)","trace[917017048] 'compare' (duration: 232.770316ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:54.384556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:53.875665Z","time spent":"508.854424ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:05:54.660674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.311996ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-05T16:05:54.771687Z","caller":"traceutil/trace.go:171","msg":"trace[1712513065] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"106.269295ms","start":"2026-06-05T16:05:54.665388Z","end":"2026-06-05T16:05:54.771657Z","steps":["trace[1712513065] 'process raft request' (duration: 106.003322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:55.315142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.627613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:05:55.31534Z","caller":"traceutil/trace.go:171","msg":"trace[2079702382] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"193.428248ms","start":"2026-06-05T16:05:55.121897Z","end":"2026-06-05T16:05:55.315325Z","steps":["trace[2079702382] 'process raft request' (duration: 193.345267ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:55.315597Z","caller":"traceutil/trace.go:171","msg":"trace[1398137665] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"540.088491ms","start":"2026-06-05T16:05:54.775498Z","end":"2026-06-05T16:05:55.315586Z","steps":["trace[1398137665] 'process raft request' (duration: 310.940072ms)","trace[1398137665] 'compare' (duration: 228.171438ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:55.315642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:54.775474Z","time spent":"540.143701ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:05:55.315737Z","caller":"traceutil/trace.go:171","msg":"trace[1597091616] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1268; }","duration":"450.169671ms","start":"2026-06-05T16:05:54.86556Z","end":"2026-06-05T16:05:55.31573Z","steps":["trace[1597091616] 'read index received' (duration: 220.902131ms)","trace[1597091616] 'applied index is now lower than readState.Index' (duration: 229.26674ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:55.315779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.215261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:05:55.315797Z","caller":"traceutil/trace.go:171","msg":"trace[1880258703] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1218; }","duration":"450.269811ms","start":"2026-06-05T16:05:54.86552Z","end":"2026-06-05T16:05:55.31579Z","steps":["trace[1880258703] 'agreement among raft nodes before linearized reading' (duration: 450.233201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:05:55.315849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:54.865495Z","time spent":"450.347752ms","remote":"127.0.0.1:56406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-06-05T16:05:55.615768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.9677ms","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-06-05T16:05:55.61589Z","caller":"traceutil/trace.go:171","msg":"trace[1540584752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1218; }","duration":"150.149512ms","start":"2026-06-05T16:05:55.465721Z","end":"2026-06-05T16:05:55.61587Z","steps":["trace[1540584752] 'range keys from in-memory index tree' (duration: 149.730368ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:05:55.908465Z","caller":"traceutil/trace.go:171","msg":"trace[1305954640] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"288.498504ms","start":"2026-06-05T16:05:55.619918Z","end":"2026-06-05T16:05:55.908417Z","steps":["trace[1305954640] 'process raft request' (duration: 273.114971ms)","trace[1305954640] 'compare' (duration: 15.11458ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:56.321274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.860484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:05:56.321415Z","caller":"traceutil/trace.go:171","msg":"trace[78343041] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"342.722381ms","start":"2026-06-05T16:05:55.978667Z","end":"2026-06-05T16:05:56.32139Z","steps":["trace[78343041] 'process raft request' (duration: 159.603554ms)","trace[78343041] 'compare' (duration: 182.379379ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:05:56.321488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:05:55.978626Z","time spent":"342.829262ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:06:01.886238Z","caller":"traceutil/trace.go:171","msg":"trace[2045044582] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"213.222262ms","start":"2026-06-05T16:06:01.672957Z","end":"2026-06-05T16:06:01.886179Z","steps":["trace[2045044582] 'process raft request' (duration: 212.99474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:02.154016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.761645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:06:02.154262Z","caller":"traceutil/trace.go:171","msg":"trace[1594996374] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"220.208464ms","start":"2026-06-05T16:06:01.934013Z","end":"2026-06-05T16:06:02.154222Z","steps":["trace[1594996374] 'read index received' (duration: 34.094256ms)","trace[1594996374] 'applied index is now lower than readState.Index' (duration: 186.111578ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:06:02.154592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.578459ms","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-06-05T16:06:02.154687Z","caller":"traceutil/trace.go:171","msg":"trace[2135472273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"220.71095ms","start":"2026-06-05T16:06:01.933937Z","end":"2026-06-05T16:06:02.154648Z","steps":["trace[2135472273] 'agreement among raft nodes before linearized reading' (duration: 220.374766ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:02.154739Z","caller":"traceutil/trace.go:171","msg":"trace[475253252] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"306.73729ms","start":"2026-06-05T16:06:01.847962Z","end":"2026-06-05T16:06:02.154699Z","steps":["trace[475253252] 'process raft request' (duration: 120.116746ms)","trace[475253252] 'compare' (duration: 185.184508ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:06:02.154912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:06:01.847934Z","time spent":"306.872971ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:06:02.432027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.542913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T16:06:02.432109Z","caller":"traceutil/trace.go:171","msg":"trace[378606785] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1259; }","duration":"157.656704ms","start":"2026-06-05T16:06:02.274433Z","end":"2026-06-05T16:06:02.43209Z","steps":["trace[378606785] 'count revisions from in-memory index tree' (duration: 157.444382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:02.764485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.425315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:06:02.764673Z","caller":"traceutil/trace.go:171","msg":"trace[1166200972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"161.856439ms","start":"2026-06-05T16:06:02.602788Z","end":"2026-06-05T16:06:02.764645Z","steps":["trace[1166200972] 'range keys from in-memory index tree' (duration: 161.353844ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:02.916723Z","caller":"traceutil/trace.go:171","msg":"trace[2059078783] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"116.008616ms","start":"2026-06-05T16:06:02.800678Z","end":"2026-06-05T16:06:02.916686Z","steps":["trace[2059078783] 'read index received' (duration: 115.850774ms)","trace[2059078783] 'applied index is now lower than readState.Index' (duration: 155.262µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:06:02.916815Z","caller":"traceutil/trace.go:171","msg":"trace[1877376524] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"145.045736ms","start":"2026-06-05T16:06:02.771727Z","end":"2026-06-05T16:06:02.916772Z","steps":["trace[1877376524] 'process raft request' (duration: 144.810263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:02.917146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.446041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-05T16:06:02.917218Z","caller":"traceutil/trace.go:171","msg":"trace[1012464749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1261; }","duration":"116.555652ms","start":"2026-06-05T16:06:02.800644Z","end":"2026-06-05T16:06:02.917199Z","steps":["trace[1012464749] 'agreement among raft nodes before linearized reading' (duration: 116.35153ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:03.285236Z","caller":"traceutil/trace.go:171","msg":"trace[357494735] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"362.406489ms","start":"2026-06-05T16:06:02.922789Z","end":"2026-06-05T16:06:03.285196Z","steps":["trace[357494735] 'process raft request' (duration: 329.062103ms)","trace[357494735] 'compare' (duration: 32.760689ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:06:03.285625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:06:02.922771Z","time spent":"362.639101ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:06:03.29564Z","caller":"traceutil/trace.go:171","msg":"trace[802950263] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"372.52781ms","start":"2026-06-05T16:06:02.923086Z","end":"2026-06-05T16:06:03.295614Z","steps":["trace[802950263] 'process raft request' (duration: 372.377658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:03.295888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:06:02.923079Z","time spent":"372.634361ms","remote":"127.0.0.1:51100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3884,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:06:03.464637Z","caller":"traceutil/trace.go:171","msg":"trace[577058058] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1322; }","duration":"102.782438ms","start":"2026-06-05T16:06:03.361833Z","end":"2026-06-05T16:06:03.464616Z","steps":["trace[577058058] 'read index received' (duration: 3.500599ms)","trace[577058058] 'applied index is now lower than readState.Index' (duration: 99.281059ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:06:03.464653Z","caller":"traceutil/trace.go:171","msg":"trace[549264610] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"114.684588ms","start":"2026-06-05T16:06:03.349942Z","end":"2026-06-05T16:06:03.464627Z","steps":["trace[549264610] 'process raft request' (duration: 114.500676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:03.464933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.082951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T16:06:03.464968Z","caller":"traceutil/trace.go:171","msg":"trace[695959985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1270; }","duration":"103.175982ms","start":"2026-06-05T16:06:03.361784Z","end":"2026-06-05T16:06:03.46496Z","steps":["trace[695959985] 'agreement among raft nodes before linearized reading' (duration: 102.916029ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:06.009322Z","caller":"traceutil/trace.go:171","msg":"trace[712177626] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"103.625001ms","start":"2026-06-05T16:06:05.905668Z","end":"2026-06-05T16:06:06.009293Z","steps":["trace[712177626] 'process raft request' (duration: 103.319268ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:06.955554Z","caller":"traceutil/trace.go:171","msg":"trace[1354002794] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"325.961899ms","start":"2026-06-05T16:06:06.629574Z","end":"2026-06-05T16:06:06.955536Z","steps":["trace[1354002794] 'read index received' (duration: 325.757137ms)","trace[1354002794] 'applied index is now lower than readState.Index' (duration: 201.952µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:06:06.955653Z","caller":"traceutil/trace.go:171","msg":"trace[1583489785] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"479.500643ms","start":"2026-06-05T16:06:06.476144Z","end":"2026-06-05T16:06:06.955644Z","steps":["trace[1583489785] 'process raft request' (duration: 479.27762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:06.955732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:06:06.476132Z","time spent":"479.541073ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:06:06.955825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.216223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-06-05T16:06:06.955863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.322091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:39440"} {"level":"info","ts":"2026-06-05T16:06:06.955908Z","caller":"traceutil/trace.go:171","msg":"trace[360456303] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1287; }","duration":"326.382614ms","start":"2026-06-05T16:06:06.629508Z","end":"2026-06-05T16:06:06.955891Z","steps":["trace[360456303] 'agreement among raft nodes before linearized reading' (duration: 326.214602ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:06.955916Z","caller":"traceutil/trace.go:171","msg":"trace[2083694754] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1287; }","duration":"323.411322ms","start":"2026-06-05T16:06:06.63249Z","end":"2026-06-05T16:06:06.955902Z","steps":["trace[2083694754] 'agreement among raft nodes before linearized reading' (duration: 323.18169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:06.955957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:06:06.629488Z","time spent":"326.458445ms","remote":"127.0.0.1:50980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-06-05T16:06:06.955962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:06:06.632473Z","time spent":"323.474353ms","remote":"127.0.0.1:50974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":39462,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-06-05T16:06:07.513913Z","caller":"traceutil/trace.go:171","msg":"trace[1814220354] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"210.843021ms","start":"2026-06-05T16:06:07.303044Z","end":"2026-06-05T16:06:07.513887Z","steps":["trace[1814220354] 'process raft request' (duration: 210.663929ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:07.641087Z","caller":"traceutil/trace.go:171","msg":"trace[796491175] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"163.401395ms","start":"2026-06-05T16:06:07.477643Z","end":"2026-06-05T16:06:07.641044Z","steps":["trace[796491175] 'process raft request' (duration: 115.07181ms)","trace[796491175] 'compare' (duration: 48.102622ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:06:07.980307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.515566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:06:07.980398Z","caller":"traceutil/trace.go:171","msg":"trace[1393329750] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"210.974882ms","start":"2026-06-05T16:06:07.769407Z","end":"2026-06-05T16:06:07.980382Z","steps":["trace[1393329750] 'read index received' (duration: 24.307345ms)","trace[1393329750] 'applied index is now lower than readState.Index' (duration: 186.666527ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:06:07.980522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.113384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-06-05T16:06:07.980555Z","caller":"traceutil/trace.go:171","msg":"trace[809516680] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1290; }","duration":"211.183304ms","start":"2026-06-05T16:06:07.769363Z","end":"2026-06-05T16:06:07.980546Z","steps":["trace[809516680] 'agreement among raft nodes before linearized reading' (duration: 211.061673ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:07.980493Z","caller":"traceutil/trace.go:171","msg":"trace[204212148] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"334.812667ms","start":"2026-06-05T16:06:07.645657Z","end":"2026-06-05T16:06:07.980469Z","steps":["trace[204212148] 'process raft request' (duration: 148.071199ms)","trace[204212148] 'compare' (duration: 186.289303ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:06:07.980807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:06:07.645637Z","time spent":"335.09154ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:06:08.157199Z","caller":"traceutil/trace.go:171","msg":"trace[1361859887] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"140.082698ms","start":"2026-06-05T16:06:08.017087Z","end":"2026-06-05T16:06:08.157169Z","steps":["trace[1361859887] 'process raft request' (duration: 98.497717ms)","trace[1361859887] 'compare' (duration: 41.443149ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:06:14.907698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.490865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:06:14.907796Z","caller":"traceutil/trace.go:171","msg":"trace[337099166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1319; }","duration":"304.648007ms","start":"2026-06-05T16:06:14.603124Z","end":"2026-06-05T16:06:14.907772Z","steps":["trace[337099166] 'range keys from in-memory index tree' (duration: 304.402714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:14.90788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:06:14.603103Z","time spent":"304.767309ms","remote":"127.0.0.1:50902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T16:06:14.915063Z","caller":"traceutil/trace.go:171","msg":"trace[777523052] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"125.544543ms","start":"2026-06-05T16:06:14.789492Z","end":"2026-06-05T16:06:14.915037Z","steps":["trace[777523052] 'process raft request' (duration: 125.28ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:21.794954Z","caller":"traceutil/trace.go:171","msg":"trace[738294260] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"103.843254ms","start":"2026-06-05T16:06:21.691087Z","end":"2026-06-05T16:06:21.79493Z","steps":["trace[738294260] 'read index received' (duration: 103.660832ms)","trace[738294260] 'applied index is now lower than readState.Index' (duration: 181.552µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:06:21.795648Z","caller":"traceutil/trace.go:171","msg":"trace[904570886] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"111.96364ms","start":"2026-06-05T16:06:21.683028Z","end":"2026-06-05T16:06:21.794992Z","steps":["trace[904570886] 'process raft request' (duration: 111.766568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:21.795869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.756864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" limit:500 ","response":"range_response_count:113 size:85252"} {"level":"info","ts":"2026-06-05T16:06:21.795937Z","caller":"traceutil/trace.go:171","msg":"trace[1963320509] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:113; response_revision:1346; }","duration":"104.880325ms","start":"2026-06-05T16:06:21.691042Z","end":"2026-06-05T16:06:21.795923Z","steps":["trace[1963320509] 'agreement among raft nodes before linearized reading' (duration: 104.005875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:22.27086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.31349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-06-05T16:06:22.270944Z","caller":"traceutil/trace.go:171","msg":"trace[1942966674] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:1; response_revision:1347; }","duration":"148.442511ms","start":"2026-06-05T16:06:22.122482Z","end":"2026-06-05T16:06:22.270925Z","steps":["trace[1942966674] 'range keys from in-memory index tree' (duration: 148.244719ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:22.381274Z","caller":"traceutil/trace.go:171","msg":"trace[354305231] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"104.377997ms","start":"2026-06-05T16:06:22.276545Z","end":"2026-06-05T16:06:22.380923Z","steps":["trace[354305231] 'process raft request' (duration: 104.120815ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:36.65842Z","caller":"traceutil/trace.go:171","msg":"trace[879725330] linearizableReadLoop","detail":"{readStateIndex:1457; appliedIndex:1456; }","duration":"142.713414ms","start":"2026-06-05T16:06:36.515692Z","end":"2026-06-05T16:06:36.658405Z","steps":["trace[879725330] 'read index received' (duration: 142.560053ms)","trace[879725330] 'applied index is now lower than readState.Index' (duration: 152.631µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:06:36.658513Z","caller":"traceutil/trace.go:171","msg":"trace[423707935] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"153.581235ms","start":"2026-06-05T16:06:36.504914Z","end":"2026-06-05T16:06:36.658495Z","steps":["trace[423707935] 'process raft request' (duration: 153.390833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:36.658572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.866456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:1914"} {"level":"warn","ts":"2026-06-05T16:06:36.658593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.73533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-node-lease/\" range_end:\"/registry/roles/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:06:36.658603Z","caller":"traceutil/trace.go:171","msg":"trace[1856652442] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:1395; }","duration":"142.935136ms","start":"2026-06-05T16:06:36.515659Z","end":"2026-06-05T16:06:36.658595Z","steps":["trace[1856652442] 'agreement among raft nodes before linearized reading' (duration: 142.835935ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:36.658623Z","caller":"traceutil/trace.go:171","msg":"trace[740135975] range","detail":"{range_begin:/registry/roles/kube-node-lease/; range_end:/registry/roles/kube-node-lease0; response_count:0; response_revision:1395; }","duration":"125.79755ms","start":"2026-06-05T16:06:36.532816Z","end":"2026-06-05T16:06:36.658614Z","steps":["trace[740135975] 'agreement among raft nodes before linearized reading' (duration: 125.74772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:36.658837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.212871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-06-05T16:06:36.658885Z","caller":"traceutil/trace.go:171","msg":"trace[513141673] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1395; }","duration":"108.298931ms","start":"2026-06-05T16:06:36.550573Z","end":"2026-06-05T16:06:36.658871Z","steps":["trace[513141673] 'agreement among raft nodes before linearized reading' (duration: 108.21252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:38.622032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.321867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/kube-root-ca.crt\" ","response":"range_response_count:1 size:1760"} {"level":"info","ts":"2026-06-05T16:06:38.62212Z","caller":"traceutil/trace.go:171","msg":"trace[257958551] range","detail":"{range_begin:/registry/configmaps/local-path-storage/kube-root-ca.crt; range_end:; response_count:1; response_revision:1401; }","duration":"101.448387ms","start":"2026-06-05T16:06:38.520644Z","end":"2026-06-05T16:06:38.622092Z","steps":["trace[257958551] 'range keys from in-memory index tree' (duration: 101.154765ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:39.300257Z","caller":"traceutil/trace.go:171","msg":"trace[1753755480] linearizableReadLoop","detail":"{readStateIndex:1467; appliedIndex:1466; }","duration":"145.0022ms","start":"2026-06-05T16:06:39.155229Z","end":"2026-06-05T16:06:39.300231Z","steps":["trace[1753755480] 'read index received' (duration: 144.633586ms)","trace[1753755480] 'applied index is now lower than readState.Index' (duration: 367.334µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:06:39.3003Z","caller":"traceutil/trace.go:171","msg":"trace[1197192357] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"146.240952ms","start":"2026-06-05T16:06:39.154032Z","end":"2026-06-05T16:06:39.300273Z","steps":["trace[1197192357] 'process raft request' (duration: 145.765548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:39.301011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.759948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:69 size:51161"} {"level":"info","ts":"2026-06-05T16:06:39.301066Z","caller":"traceutil/trace.go:171","msg":"trace[388305752] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:69; response_revision:1404; }","duration":"145.846758ms","start":"2026-06-05T16:06:39.155204Z","end":"2026-06-05T16:06:39.301051Z","steps":["trace[388305752] 'agreement among raft nodes before linearized reading' (duration: 145.193502ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:41.466037Z","caller":"traceutil/trace.go:171","msg":"trace[2012942069] linearizableReadLoop","detail":"{readStateIndex:1473; appliedIndex:1472; }","duration":"115.211463ms","start":"2026-06-05T16:06:41.350802Z","end":"2026-06-05T16:06:41.466014Z","steps":["trace[2012942069] 'read index received' (duration: 115.033501ms)","trace[2012942069] 'applied index is now lower than readState.Index' (duration: 177.112µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:06:41.466145Z","caller":"traceutil/trace.go:171","msg":"trace[1149362948] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"159.308782ms","start":"2026-06-05T16:06:41.306826Z","end":"2026-06-05T16:06:41.466135Z","steps":["trace[1149362948] 'process raft request' (duration: 159.055479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:41.466315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.503505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader\" ","response":"range_response_count:1 size:856"} {"level":"info","ts":"2026-06-05T16:06:41.466355Z","caller":"traceutil/trace.go:171","msg":"trace[684989596] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader; range_end:; response_count:1; response_revision:1410; }","duration":"115.578177ms","start":"2026-06-05T16:06:41.350766Z","end":"2026-06-05T16:06:41.466344Z","steps":["trace[684989596] 'agreement among raft nodes before linearized reading' (duration: 115.497906ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:51.799295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.597654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:06:51.79939Z","caller":"traceutil/trace.go:171","msg":"trace[1433300499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1443; }","duration":"195.714865ms","start":"2026-06-05T16:06:51.603654Z","end":"2026-06-05T16:06:51.799369Z","steps":["trace[1433300499] 'range keys from in-memory index tree' (duration: 195.526343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:51.799284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.608485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-tnmwz\" ","response":"range_response_count:1 size:4908"} {"level":"info","ts":"2026-06-05T16:06:51.799537Z","caller":"traceutil/trace.go:171","msg":"trace[822801267] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-tnmwz; range_end:; response_count:1; response_revision:1443; }","duration":"232.892408ms","start":"2026-06-05T16:06:51.566617Z","end":"2026-06-05T16:06:51.799509Z","steps":["trace[822801267] 'range keys from in-memory index tree' (duration: 232.432504ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:52.131491Z","caller":"traceutil/trace.go:171","msg":"trace[1100986696] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"125.047134ms","start":"2026-06-05T16:06:52.006401Z","end":"2026-06-05T16:06:52.131449Z","steps":["trace[1100986696] 'process raft request' (duration: 124.796551ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:06:52.325806Z","caller":"traceutil/trace.go:171","msg":"trace[2143633170] linearizableReadLoop","detail":"{readStateIndex:1510; appliedIndex:1509; }","duration":"121.499818ms","start":"2026-06-05T16:06:52.204278Z","end":"2026-06-05T16:06:52.325777Z","steps":["trace[2143633170] 'read index received' (duration: 120.330066ms)","trace[2143633170] 'applied index is now lower than readState.Index' (duration: 1.168532ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:06:52.326005Z","caller":"traceutil/trace.go:171","msg":"trace[1358434600] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"190.49215ms","start":"2026-06-05T16:06:52.135468Z","end":"2026-06-05T16:06:52.32596Z","steps":["trace[1358434600] 'process raft request' (duration: 189.111506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:06:52.326108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.795391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-06-05T16:06:52.326373Z","caller":"traceutil/trace.go:171","msg":"trace[470873432] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1445; }","duration":"122.062523ms","start":"2026-06-05T16:06:52.204243Z","end":"2026-06-05T16:06:52.326306Z","steps":["trace[470873432] 'agreement among raft nodes before linearized reading' (duration: 121.74581ms)"],"step_count":1}