{"level":"warn","ts":"2026-08-19T20:27:19.421294Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T20:27:19.421372Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.201: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.19.213.201:2380","--initial-cluster=instance=https://199.19.213.201:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.201:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.201: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-08-19T20:27:19.421434Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T20:27:19.421444Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-08-19T20:27:19.421462Z","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-08-19T20:27:19.422047Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"]} {"level":"info","ts":"2026-08-19T20:27:19.422116Z","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.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.201: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-08-19T20:27:19.492164Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"69.839284ms"} {"level":"info","ts":"2026-08-19T20:27:19.578419Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"61bc451777564e43","cluster-id":"b1814cb801e33ddc"} {"level":"info","ts":"2026-08-19T20:27:19.578558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=()"} {"level":"info","ts":"2026-08-19T20:27:19.578608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 0"} {"level":"info","ts":"2026-08-19T20:27:19.578622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 61bc451777564e43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-19T20:27:19.578631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 1"} {"level":"info","ts":"2026-08-19T20:27:19.578671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"warn","ts":"2026-08-19T20:27:19.796598Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-19T20:27:19.80895Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-19T20:27:19.833565Z","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-08-19T20:27:19.907113Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"61bc451777564e43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-19T20:27:19.907359Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"61bc451777564e43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-19T20:27:19.90743Z","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-08-19T20:27:19.907454Z","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-08-19T20:27:19.907461Z","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-08-19T20:27:19.915978Z","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-08-19T20:27:19.916049Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-08-19T20:27:19.916127Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-08-19T20:27:19.916707Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"61bc451777564e43","initial-advertise-peer-urls":["https://199.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-19T20:27:19.91676Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-19T20:27:19.939051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"info","ts":"2026-08-19T20:27:19.93923Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","added-peer-id":"61bc451777564e43","added-peer-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-08-19T20:27:20.189531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 is starting a new election at term 1"} {"level":"info","ts":"2026-08-19T20:27:20.189602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-19T20:27:20.189637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgPreVoteResp from 61bc451777564e43 at term 1"} {"level":"info","ts":"2026-08-19T20:27:20.189651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became candidate at term 2"} {"level":"info","ts":"2026-08-19T20:27:20.189659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgVoteResp from 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-08-19T20:27:20.189674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became leader at term 2"} {"level":"info","ts":"2026-08-19T20:27:20.189685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 61bc451777564e43 elected leader 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-08-19T20:27:20.223212Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:27:20.253278Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T20:27:20.253279Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"61bc451777564e43","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.201:2379]}","request-path":"/0/members/61bc451777564e43/attributes","cluster-id":"b1814cb801e33ddc","publish-timeout":"7s"} {"level":"info","ts":"2026-08-19T20:27:20.253428Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T20:27:20.254484Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-19T20:27:20.25453Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-19T20:27:20.255975Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-19T20:27:20.256299Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.201:2379"} {"level":"info","ts":"2026-08-19T20:27:20.288712Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:27:20.288875Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:27:20.288927Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:27:22.187841Z","caller":"traceutil/trace.go:171","msg":"trace[319040073] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"141.722011ms","start":"2026-08-19T20:27:22.046099Z","end":"2026-08-19T20:27:22.187821Z","steps":["trace[319040073] 'process raft request' (duration: 65.135168ms)","trace[319040073] 'compare' (duration: 76.457981ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:22.187893Z","caller":"traceutil/trace.go:171","msg":"trace[1558798548] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"131.407843ms","start":"2026-08-19T20:27:22.056464Z","end":"2026-08-19T20:27:22.187872Z","steps":["trace[1558798548] 'read index received' (duration: 54.713149ms)","trace[1558798548] 'applied index is now lower than readState.Index' (duration: 76.692904ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:22.188111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.604037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-19T20:27:22.188172Z","caller":"traceutil/trace.go:171","msg":"trace[1533505826] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:4; }","duration":"131.747458ms","start":"2026-08-19T20:27:22.056411Z","end":"2026-08-19T20:27:22.188158Z","steps":["trace[1533505826] 'agreement among raft nodes before linearized reading' (duration: 131.574126ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:22.858572Z","caller":"traceutil/trace.go:171","msg":"trace[246722733] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"474.056248ms","start":"2026-08-19T20:27:22.384493Z","end":"2026-08-19T20:27:22.85855Z","steps":["trace[246722733] 'process raft request' (duration: 468.460618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:22.859055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:22.384475Z","time spent":"474.170499ms","remote":"127.0.0.1:59060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:27:22.859207Z","caller":"traceutil/trace.go:171","msg":"trace[373999406] linearizableReadLoop","detail":"{readStateIndex:41; appliedIndex:40; }","duration":"473.847825ms","start":"2026-08-19T20:27:22.385348Z","end":"2026-08-19T20:27:22.859196Z","steps":["trace[373999406] 'read index received' (duration: 467.586086ms)","trace[373999406] 'applied index is now lower than readState.Index' (duration: 6.260579ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:22.85928Z","caller":"traceutil/trace.go:171","msg":"trace[264755521] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"473.805444ms","start":"2026-08-19T20:27:22.385466Z","end":"2026-08-19T20:27:22.859272Z","steps":["trace[264755521] 'process raft request' (duration: 472.585977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:22.859464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:22.385452Z","time spent":"473.855326ms","remote":"127.0.0.1:59060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1058,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:27:22.859765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.413964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-19T20:27:22.859812Z","caller":"traceutil/trace.go:171","msg":"trace[637385008] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:39; }","duration":"474.505495ms","start":"2026-08-19T20:27:22.385294Z","end":"2026-08-19T20:27:22.8598Z","steps":["trace[637385008] 'agreement among raft nodes before linearized reading' (duration: 474.441905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:22.859842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:22.385269Z","time spent":"474.566416ms","remote":"127.0.0.1:58682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" "} {"level":"warn","ts":"2026-08-19T20:27:22.85998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.162296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-19T20:27:22.860015Z","caller":"traceutil/trace.go:171","msg":"trace[1901409596] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:39; }","duration":"466.212707ms","start":"2026-08-19T20:27:22.393794Z","end":"2026-08-19T20:27:22.860007Z","steps":["trace[1901409596] 'agreement among raft nodes before linearized reading' (duration: 466.162717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:22.860041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:22.393787Z","time spent":"466.248818ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-08-19T20:27:22.860493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.28434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-19T20:27:22.860537Z","caller":"traceutil/trace.go:171","msg":"trace[885503968] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:39; }","duration":"309.367031ms","start":"2026-08-19T20:27:22.551161Z","end":"2026-08-19T20:27:22.860528Z","steps":["trace[885503968] 'agreement among raft nodes before linearized reading' (duration: 309.30689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:22.860579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:22.551138Z","time spent":"309.420712ms","remote":"127.0.0.1:59020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-08-19T20:27:23.053615Z","caller":"traceutil/trace.go:171","msg":"trace[952226218] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"188.503907ms","start":"2026-08-19T20:27:22.865089Z","end":"2026-08-19T20:27:23.053593Z","steps":["trace[952226218] 'process raft request' (duration: 117.80024ms)","trace[952226218] 'compare' (duration: 70.579275ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:23.053726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.413909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-bjd8q\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-08-19T20:27:23.053745Z","caller":"traceutil/trace.go:171","msg":"trace[1888448437] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-bjd8q; range_end:; response_count:1; response_revision:44; }","duration":"189.46407ms","start":"2026-08-19T20:27:22.864276Z","end":"2026-08-19T20:27:23.05374Z","steps":["trace[1888448437] 'agreement among raft nodes before linearized reading' (duration: 189.400329ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:23.05363Z","caller":"traceutil/trace.go:171","msg":"trace[501563587] linearizableReadLoop","detail":"{readStateIndex:48; appliedIndex:44; }","duration":"131.406372ms","start":"2026-08-19T20:27:22.922213Z","end":"2026-08-19T20:27:23.05362Z","steps":["trace[501563587] 'read index received' (duration: 60.645275ms)","trace[501563587] 'applied index is now lower than readState.Index' (duration: 70.760637ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:23.05381Z","caller":"traceutil/trace.go:171","msg":"trace[1911129409] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"188.12987ms","start":"2026-08-19T20:27:22.865628Z","end":"2026-08-19T20:27:23.053757Z","steps":["trace[1911129409] 'process raft request' (duration: 187.949667ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:23.053921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.495628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-19T20:27:23.053958Z","caller":"traceutil/trace.go:171","msg":"trace[1032476982] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:44; }","duration":"111.56159ms","start":"2026-08-19T20:27:22.942384Z","end":"2026-08-19T20:27:23.053946Z","steps":["trace[1032476982] 'agreement among raft nodes before linearized reading' (duration: 111.501629ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:23.053958Z","caller":"traceutil/trace.go:171","msg":"trace[958982344] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"134.213312ms","start":"2026-08-19T20:27:22.919735Z","end":"2026-08-19T20:27:23.053948Z","steps":["trace[958982344] 'process raft request' (duration: 133.867427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:23.054087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.235252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-08-19T20:27:23.054166Z","caller":"traceutil/trace.go:171","msg":"trace[1542264523] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"188.783849ms","start":"2026-08-19T20:27:22.865359Z","end":"2026-08-19T20:27:23.054143Z","steps":["trace[1542264523] 'process raft request' (duration: 188.192521ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:23.054196Z","caller":"traceutil/trace.go:171","msg":"trace[2103045090] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:44; }","duration":"188.350754ms","start":"2026-08-19T20:27:22.865818Z","end":"2026-08-19T20:27:23.054169Z","steps":["trace[2103045090] 'agreement among raft nodes before linearized reading' (duration: 188.188201ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:23.23664Z","caller":"traceutil/trace.go:171","msg":"trace[1401067218] linearizableReadLoop","detail":"{readStateIndex:56; appliedIndex:54; }","duration":"101.735326ms","start":"2026-08-19T20:27:23.134874Z","end":"2026-08-19T20:27:23.236609Z","steps":["trace[1401067218] 'read index received' (duration: 20.757934ms)","trace[1401067218] 'applied index is now lower than readState.Index' (duration: 80.976092ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:23.236823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.927208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-19T20:27:23.236878Z","caller":"traceutil/trace.go:171","msg":"trace[1488618748] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"103.043075ms","start":"2026-08-19T20:27:23.133826Z","end":"2026-08-19T20:27:23.236869Z","steps":["trace[1488618748] 'process raft request' (duration: 102.623369ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:23.236905Z","caller":"traceutil/trace.go:171","msg":"trace[165697412] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:53; }","duration":"102.048681ms","start":"2026-08-19T20:27:23.134839Z","end":"2026-08-19T20:27:23.236888Z","steps":["trace[165697412] 'agreement among raft nodes before linearized reading' (duration: 101.916299ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:23.237018Z","caller":"traceutil/trace.go:171","msg":"trace[2141581743] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"100.555109ms","start":"2026-08-19T20:27:23.136447Z","end":"2026-08-19T20:27:23.237002Z","steps":["trace[2141581743] 'process raft request' (duration: 100.102953ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:23.236803Z","caller":"traceutil/trace.go:171","msg":"trace[1568925025] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"104.013478ms","start":"2026-08-19T20:27:23.132748Z","end":"2026-08-19T20:27:23.236762Z","steps":["trace[1568925025] 'process raft request' (duration: 22.780723ms)","trace[1568925025] 'compare' (duration: 80.696817ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:23.849332Z","caller":"traceutil/trace.go:171","msg":"trace[1229080038] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"163.24616ms","start":"2026-08-19T20:27:23.686035Z","end":"2026-08-19T20:27:23.849281Z","steps":["trace[1229080038] 'process raft request' (duration: 139.871137ms)","trace[1229080038] 'compare' (duration: 23.239581ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:24.069635Z","caller":"traceutil/trace.go:171","msg":"trace[1856656484] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"120.730114ms","start":"2026-08-19T20:27:23.948873Z","end":"2026-08-19T20:27:24.069603Z","steps":["trace[1856656484] 'process raft request' (duration: 50.188394ms)","trace[1856656484] 'compare' (duration: 70.244706ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:24.624808Z","caller":"traceutil/trace.go:171","msg":"trace[1515355045] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"183.176797ms","start":"2026-08-19T20:27:24.441602Z","end":"2026-08-19T20:27:24.624779Z","steps":["trace[1515355045] 'process raft request' (duration: 123.938047ms)","trace[1515355045] 'compare' (duration: 59.077548ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:24.829551Z","caller":"traceutil/trace.go:171","msg":"trace[328231259] linearizableReadLoop","detail":"{readStateIndex:122; appliedIndex:120; }","duration":"144.165294ms","start":"2026-08-19T20:27:24.685359Z","end":"2026-08-19T20:27:24.829524Z","steps":["trace[328231259] 'read index received' (duration: 110.72767ms)","trace[328231259] 'applied index is now lower than readState.Index' (duration: 33.436404ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:24.829713Z","caller":"traceutil/trace.go:171","msg":"trace[929092847] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"173.356458ms","start":"2026-08-19T20:27:24.656339Z","end":"2026-08-19T20:27:24.829696Z","steps":["trace[929092847] 'process raft request' (duration: 173.128014ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:24.829701Z","caller":"traceutil/trace.go:171","msg":"trace[573535846] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"173.494769ms","start":"2026-08-19T20:27:24.656181Z","end":"2026-08-19T20:27:24.829676Z","steps":["trace[573535846] 'process raft request' (duration: 140.001134ms)","trace[573535846] 'compare' (duration: 33.11614ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:24.830017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.652802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-19T20:27:24.830113Z","caller":"traceutil/trace.go:171","msg":"trace[381143450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:118; }","duration":"144.850334ms","start":"2026-08-19T20:27:24.685241Z","end":"2026-08-19T20:27:24.830091Z","steps":["trace[381143450] 'agreement among raft nodes before linearized reading' (duration: 144.454769ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:25.039432Z","caller":"traceutil/trace.go:171","msg":"trace[60181699] linearizableReadLoop","detail":"{readStateIndex:127; appliedIndex:125; }","duration":"131.588064ms","start":"2026-08-19T20:27:24.90782Z","end":"2026-08-19T20:27:25.039408Z","steps":["trace[60181699] 'read index received' (duration: 73.869927ms)","trace[60181699] 'applied index is now lower than readState.Index' (duration: 57.717097ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:25.039454Z","caller":"traceutil/trace.go:171","msg":"trace[1470903194] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"164.467341ms","start":"2026-08-19T20:27:24.874957Z","end":"2026-08-19T20:27:25.039425Z","steps":["trace[1470903194] 'process raft request' (duration: 106.630862ms)","trace[1470903194] 'compare' (duration: 57.591085ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:25.039715Z","caller":"traceutil/trace.go:171","msg":"trace[881858315] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"147.49548ms","start":"2026-08-19T20:27:24.892197Z","end":"2026-08-19T20:27:25.039692Z","steps":["trace[881858315] 'process raft request' (duration: 147.148695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:25.039556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.711516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-19T20:27:25.039797Z","caller":"traceutil/trace.go:171","msg":"trace[170529747] range","detail":"{range_begin:/registry/clusterroles/system:controller:horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:123; }","duration":"132.00167ms","start":"2026-08-19T20:27:24.907778Z","end":"2026-08-19T20:27:25.039779Z","steps":["trace[170529747] 'agreement among raft nodes before linearized reading' (duration: 131.725417ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:25.746651Z","caller":"traceutil/trace.go:171","msg":"trace[969281527] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"174.74001ms","start":"2026-08-19T20:27:25.571887Z","end":"2026-08-19T20:27:25.746627Z","steps":["trace[969281527] 'process raft request' (duration: 122.651224ms)","trace[969281527] 'compare' (duration: 51.978395ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:27.274Z","caller":"traceutil/trace.go:171","msg":"trace[1491735775] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"176.969469ms","start":"2026-08-19T20:27:27.096984Z","end":"2026-08-19T20:27:27.273954Z","steps":["trace[1491735775] 'process raft request' (duration: 149.140907ms)","trace[1491735775] 'compare' (duration: 27.573468ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:27.274021Z","caller":"traceutil/trace.go:171","msg":"trace[1449183773] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"152.710517ms","start":"2026-08-19T20:27:27.121276Z","end":"2026-08-19T20:27:27.273987Z","steps":["trace[1449183773] 'process raft request' (duration: 152.571025ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:27.447822Z","caller":"traceutil/trace.go:171","msg":"trace[1529828587] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:211; }","duration":"113.10808ms","start":"2026-08-19T20:27:27.334687Z","end":"2026-08-19T20:27:27.447795Z","steps":["trace[1529828587] 'read index received' (duration: 41.927779ms)","trace[1529828587] 'applied index is now lower than readState.Index' (duration: 71.178981ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:27.447843Z","caller":"traceutil/trace.go:171","msg":"trace[819332174] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"146.925395ms","start":"2026-08-19T20:27:27.300893Z","end":"2026-08-19T20:27:27.447819Z","steps":["trace[819332174] 'process raft request' (duration: 75.742675ms)","trace[819332174] 'compare' (duration: 71.036188ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:27.448034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.272453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:27:27.448167Z","caller":"traceutil/trace.go:171","msg":"trace[844789898] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:0; response_revision:207; }","duration":"113.482865ms","start":"2026-08-19T20:27:27.334643Z","end":"2026-08-19T20:27:27.448126Z","steps":["trace[844789898] 'agreement among raft nodes before linearized reading' (duration: 113.254092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:27.841951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.220651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:27:27.842078Z","caller":"traceutil/trace.go:171","msg":"trace[2102669269] linearizableReadLoop","detail":"{readStateIndex:225; appliedIndex:224; }","duration":"145.08551ms","start":"2026-08-19T20:27:27.696973Z","end":"2026-08-19T20:27:27.842059Z","steps":["trace[2102669269] 'read index received' (duration: 31.471053ms)","trace[2102669269] 'applied index is now lower than readState.Index' (duration: 113.613377ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:27.842136Z","caller":"traceutil/trace.go:171","msg":"trace[157888795] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"167.672128ms","start":"2026-08-19T20:27:27.674413Z","end":"2026-08-19T20:27:27.842086Z","steps":["trace[157888795] 'process raft request' (duration: 54.107192ms)","trace[157888795] 'compare' (duration: 113.061399ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:27.842187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.196031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18cd4ec5586e279e\" ","response":"range_response_count:1 size:657"} {"level":"info","ts":"2026-08-19T20:27:27.842603Z","caller":"traceutil/trace.go:171","msg":"trace[2660532] range","detail":"{range_begin:/registry/events/default/instance.18cd4ec5586e279e; range_end:; response_count:1; response_revision:220; }","duration":"145.487186ms","start":"2026-08-19T20:27:27.696934Z","end":"2026-08-19T20:27:27.842421Z","steps":["trace[2660532] 'agreement among raft nodes before linearized reading' (duration: 145.173681ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:28.43941Z","caller":"traceutil/trace.go:171","msg":"trace[185359571] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"123.636083ms","start":"2026-08-19T20:27:28.315748Z","end":"2026-08-19T20:27:28.439384Z","steps":["trace[185359571] 'process raft request' (duration: 123.480241ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:28.439493Z","caller":"traceutil/trace.go:171","msg":"trace[353815921] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"124.504095ms","start":"2026-08-19T20:27:28.314948Z","end":"2026-08-19T20:27:28.439452Z","steps":["trace[353815921] 'process raft request' (duration: 109.471645ms)","trace[353815921] 'compare' (duration: 14.657495ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:28.439501Z","caller":"traceutil/trace.go:171","msg":"trace[604885599] linearizableReadLoop","detail":"{readStateIndex:242; appliedIndex:239; }","duration":"119.659648ms","start":"2026-08-19T20:27:28.319816Z","end":"2026-08-19T20:27:28.439475Z","steps":["trace[604885599] 'read index received' (duration: 35.512577ms)","trace[604885599] 'applied index is now lower than readState.Index' (duration: 84.142151ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:28.439676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.83864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:27:28.439743Z","caller":"traceutil/trace.go:171","msg":"trace[1919463784] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:0; response_revision:237; }","duration":"119.950701ms","start":"2026-08-19T20:27:28.319783Z","end":"2026-08-19T20:27:28.439733Z","steps":["trace[1919463784] 'agreement among raft nodes before linearized reading' (duration: 119.790779ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:28.555093Z","caller":"traceutil/trace.go:171","msg":"trace[1771179346] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"102.958154ms","start":"2026-08-19T20:27:28.452013Z","end":"2026-08-19T20:27:28.554971Z","steps":["trace[1771179346] 'process raft request' (duration: 102.922734ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:28.555151Z","caller":"traceutil/trace.go:171","msg":"trace[762194219] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"108.912318ms","start":"2026-08-19T20:27:28.446207Z","end":"2026-08-19T20:27:28.555119Z","steps":["trace[762194219] 'process raft request' (duration: 108.678965ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:28.555118Z","caller":"traceutil/trace.go:171","msg":"trace[786270228] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"110.314818ms","start":"2026-08-19T20:27:28.444759Z","end":"2026-08-19T20:27:28.555073Z","steps":["trace[786270228] 'process raft request' (duration: 107.510978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:28.870743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.873913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:27:28.870848Z","caller":"traceutil/trace.go:171","msg":"trace[2064140598] linearizableReadLoop","detail":"{readStateIndex:252; appliedIndex:251; }","duration":"274.964126ms","start":"2026-08-19T20:27:28.595868Z","end":"2026-08-19T20:27:28.870832Z","steps":["trace[2064140598] 'read index received' (duration: 136.864399ms)","trace[2064140598] 'applied index is now lower than readState.Index' (duration: 138.098607ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:28.870921Z","caller":"traceutil/trace.go:171","msg":"trace[1150401940] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"276.252293ms","start":"2026-08-19T20:27:28.594626Z","end":"2026-08-19T20:27:28.870878Z","steps":["trace[1150401940] 'process raft request' (duration: 138.121487ms)","trace[1150401940] 'compare' (duration: 137.746351ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:28.871084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.182559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:27:28.871176Z","caller":"traceutil/trace.go:171","msg":"trace[1026571778] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:0; response_revision:246; }","duration":"275.332461ms","start":"2026-08-19T20:27:28.595819Z","end":"2026-08-19T20:27:28.871151Z","steps":["trace[1026571778] 'agreement among raft nodes before linearized reading' (duration: 275.063287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:29.107083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.634864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:27:29.107374Z","caller":"traceutil/trace.go:171","msg":"trace[349805615] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"162.375674ms","start":"2026-08-19T20:27:28.944979Z","end":"2026-08-19T20:27:29.107355Z","steps":["trace[349805615] 'process raft request' (duration: 27.396305ms)","trace[349805615] 'compare' (duration: 134.528732ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:29.107447Z","caller":"traceutil/trace.go:171","msg":"trace[95801386] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"160.413595ms","start":"2026-08-19T20:27:28.946998Z","end":"2026-08-19T20:27:29.107412Z","steps":["trace[95801386] 'process raft request' (duration: 160.271323ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:29.107709Z","caller":"traceutil/trace.go:171","msg":"trace[1442691334] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"160.913113ms","start":"2026-08-19T20:27:28.946704Z","end":"2026-08-19T20:27:29.107617Z","steps":["trace[1442691334] 'process raft request' (duration: 160.502878ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:29.491065Z","caller":"traceutil/trace.go:171","msg":"trace[1611319987] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"149.38903ms","start":"2026-08-19T20:27:29.341643Z","end":"2026-08-19T20:27:29.491032Z","steps":["trace[1611319987] 'process raft request' (duration: 92.465383ms)","trace[1611319987] 'compare' (duration: 56.660703ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:29.49105Z","caller":"traceutil/trace.go:171","msg":"trace[275375778] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"123.610549ms","start":"2026-08-19T20:27:29.367416Z","end":"2026-08-19T20:27:29.491027Z","steps":["trace[275375778] 'process raft request' (duration: 123.571348ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:29.491147Z","caller":"traceutil/trace.go:171","msg":"trace[1638359277] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"148.603148ms","start":"2026-08-19T20:27:29.342506Z","end":"2026-08-19T20:27:29.491109Z","steps":["trace[1638359277] 'process raft request' (duration: 148.412315ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:30.617082Z","caller":"traceutil/trace.go:171","msg":"trace[1727110936] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"161.697836ms","start":"2026-08-19T20:27:30.455355Z","end":"2026-08-19T20:27:30.617053Z","steps":["trace[1727110936] 'process raft request' (duration: 88.882551ms)","trace[1727110936] 'compare' (duration: 72.46145ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:30.617325Z","caller":"traceutil/trace.go:171","msg":"trace[158055950] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"142.141114ms","start":"2026-08-19T20:27:30.475086Z","end":"2026-08-19T20:27:30.617227Z","steps":["trace[158055950] 'process raft request' (duration: 141.924321ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:30.733293Z","caller":"traceutil/trace.go:171","msg":"trace[287227796] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"109.774861ms","start":"2026-08-19T20:27:30.623497Z","end":"2026-08-19T20:27:30.733272Z","steps":["trace[287227796] 'process raft request' (duration: 109.66806ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:30.811863Z","caller":"traceutil/trace.go:171","msg":"trace[655815975] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"187.955392ms","start":"2026-08-19T20:27:30.623872Z","end":"2026-08-19T20:27:30.811827Z","steps":["trace[655815975] 'process raft request' (duration: 187.77227ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:30.811916Z","caller":"traceutil/trace.go:171","msg":"trace[508875643] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"187.922922ms","start":"2026-08-19T20:27:30.623972Z","end":"2026-08-19T20:27:30.811895Z","steps":["trace[508875643] 'read index received' (duration: 109.443297ms)","trace[508875643] 'applied index is now lower than readState.Index' (duration: 78.478935ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:30.811934Z","caller":"traceutil/trace.go:171","msg":"trace[2077530358] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"152.787611ms","start":"2026-08-19T20:27:30.659124Z","end":"2026-08-19T20:27:30.811912Z","steps":["trace[2077530358] 'process raft request' (duration: 152.68559ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:30.811961Z","caller":"traceutil/trace.go:171","msg":"trace[2066033992] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"187.73658ms","start":"2026-08-19T20:27:30.624203Z","end":"2026-08-19T20:27:30.811939Z","steps":["trace[2066033992] 'process raft request' (duration: 187.546457ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:30.812004Z","caller":"traceutil/trace.go:171","msg":"trace[1936504546] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"151.511433ms","start":"2026-08-19T20:27:30.660487Z","end":"2026-08-19T20:27:30.811998Z","steps":["trace[1936504546] 'process raft request' (duration: 151.371882ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:30.811992Z","caller":"traceutil/trace.go:171","msg":"trace[840822758] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"152.254354ms","start":"2026-08-19T20:27:30.6597Z","end":"2026-08-19T20:27:30.811955Z","steps":["trace[840822758] 'process raft request' (duration: 152.144532ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:30.812132Z","caller":"traceutil/trace.go:171","msg":"trace[121012381] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"174.664736ms","start":"2026-08-19T20:27:30.637458Z","end":"2026-08-19T20:27:30.812122Z","steps":["trace[121012381] 'process raft request' (duration: 174.335732ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:30.812193Z","caller":"traceutil/trace.go:171","msg":"trace[168979522] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"153.023534ms","start":"2026-08-19T20:27:30.65916Z","end":"2026-08-19T20:27:30.812183Z","steps":["trace[168979522] 'process raft request' (duration: 152.667369ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:30.812212Z","caller":"traceutil/trace.go:171","msg":"trace[265688144] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"151.537824ms","start":"2026-08-19T20:27:30.660668Z","end":"2026-08-19T20:27:30.812206Z","steps":["trace[265688144] 'process raft request' (duration: 151.20665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:30.812294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.303557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-08-19T20:27:30.812343Z","caller":"traceutil/trace.go:171","msg":"trace[602285753] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:306; }","duration":"188.373458ms","start":"2026-08-19T20:27:30.623951Z","end":"2026-08-19T20:27:30.812324Z","steps":["trace[602285753] 'agreement among raft nodes before linearized reading' (duration: 188.276067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:30.818771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.120369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:27:30.818918Z","caller":"traceutil/trace.go:171","msg":"trace[1207224815] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:307; }","duration":"194.280341ms","start":"2026-08-19T20:27:30.624612Z","end":"2026-08-19T20:27:30.818892Z","steps":["trace[1207224815] 'agreement among raft nodes before linearized reading' (duration: 193.997907ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:31.03993Z","caller":"traceutil/trace.go:171","msg":"trace[808831674] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:322; }","duration":"100.518891ms","start":"2026-08-19T20:27:30.939389Z","end":"2026-08-19T20:27:31.039908Z","steps":["trace[808831674] 'read index received' (duration: 87.650341ms)","trace[808831674] 'applied index is now lower than readState.Index' (duration: 12.8678ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:31.040053Z","caller":"traceutil/trace.go:171","msg":"trace[531133851] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"123.997819ms","start":"2026-08-19T20:27:30.916018Z","end":"2026-08-19T20:27:31.040016Z","steps":["trace[531133851] 'process raft request' (duration: 123.822226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:31.040081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.687243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:27:31.040171Z","caller":"traceutil/trace.go:171","msg":"trace[1948076030] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:313; }","duration":"100.820156ms","start":"2026-08-19T20:27:30.939338Z","end":"2026-08-19T20:27:31.040158Z","steps":["trace[1948076030] 'agreement among raft nodes before linearized reading' (duration: 100.703594ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:31.040164Z","caller":"traceutil/trace.go:171","msg":"trace[59282146] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"121.082309ms","start":"2026-08-19T20:27:30.919032Z","end":"2026-08-19T20:27:31.040114Z","steps":["trace[59282146] 'process raft request' (duration: 120.840726ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:31.040119Z","caller":"traceutil/trace.go:171","msg":"trace[1329179831] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"127.450247ms","start":"2026-08-19T20:27:30.912634Z","end":"2026-08-19T20:27:31.040084Z","steps":["trace[1329179831] 'process raft request' (duration: 114.482575ms)","trace[1329179831] 'compare' (duration: 12.634997ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:31.418639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.915925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:27:31.419027Z","caller":"traceutil/trace.go:171","msg":"trace[1692580022] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"143.545077ms","start":"2026-08-19T20:27:31.275425Z","end":"2026-08-19T20:27:31.41897Z","steps":["trace[1692580022] 'process raft request' (duration: 42.225148ms)","trace[1692580022] 'compare' (duration: 100.676711ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:31.671054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.039762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-19T20:27:31.671126Z","caller":"traceutil/trace.go:171","msg":"trace[573908900] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:327; }","duration":"146.159624ms","start":"2026-08-19T20:27:31.524953Z","end":"2026-08-19T20:27:31.671112Z","steps":["trace[573908900] 'range keys from in-memory index tree' (duration: 145.944181ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:31.777525Z","caller":"traceutil/trace.go:171","msg":"trace[2147005000] transaction","detail":"{read_only:false; number_of_response:0; response_revision:327; }","duration":"198.503712ms","start":"2026-08-19T20:27:31.578999Z","end":"2026-08-19T20:27:31.777503Z","steps":["trace[2147005000] 'process raft request' (duration: 198.411541ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:31.827801Z","caller":"traceutil/trace.go:171","msg":"trace[1026979356] transaction","detail":"{read_only:false; number_of_response:0; response_revision:327; }","duration":"248.314466ms","start":"2026-08-19T20:27:31.579465Z","end":"2026-08-19T20:27:31.82778Z","steps":["trace[1026979356] 'process raft request' (duration: 248.145904ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:31.827838Z","caller":"traceutil/trace.go:171","msg":"trace[955007306] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:339; }","duration":"153.206102ms","start":"2026-08-19T20:27:31.674608Z","end":"2026-08-19T20:27:31.827814Z","steps":["trace[955007306] 'read index received' (duration: 102.807341ms)","trace[955007306] 'applied index is now lower than readState.Index' (duration: 50.397881ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:31.827942Z","caller":"traceutil/trace.go:171","msg":"trace[831788738] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"148.493996ms","start":"2026-08-19T20:27:31.679424Z","end":"2026-08-19T20:27:31.827918Z","steps":["trace[831788738] 'process raft request' (duration: 148.282123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:31.828079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.438865ms","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-08-19T20:27:31.828185Z","caller":"traceutil/trace.go:171","msg":"trace[839980922] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:328; }","duration":"153.576127ms","start":"2026-08-19T20:27:31.674564Z","end":"2026-08-19T20:27:31.82814Z","steps":["trace[839980922] 'agreement among raft nodes before linearized reading' (duration: 153.411444ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:32.048242Z","caller":"traceutil/trace.go:171","msg":"trace[1608111154] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:343; }","duration":"209.268501ms","start":"2026-08-19T20:27:31.838951Z","end":"2026-08-19T20:27:32.048219Z","steps":["trace[1608111154] 'read index received' (duration: 133.60926ms)","trace[1608111154] 'applied index is now lower than readState.Index' (duration: 75.658261ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:32.048512Z","caller":"traceutil/trace.go:171","msg":"trace[826960750] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"210.239804ms","start":"2026-08-19T20:27:31.83824Z","end":"2026-08-19T20:27:32.04848Z","steps":["trace[826960750] 'process raft request' (duration: 134.38001ms)","trace[826960750] 'compare' (duration: 75.422118ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:32.048725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.381193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:27:32.048853Z","caller":"traceutil/trace.go:171","msg":"trace[1218955687] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:330; }","duration":"209.834419ms","start":"2026-08-19T20:27:31.838928Z","end":"2026-08-19T20:27:32.048763Z","steps":["trace[1218955687] 'agreement among raft nodes before linearized reading' (duration: 209.377353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:32.354531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.486884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:27:32.354778Z","caller":"traceutil/trace.go:171","msg":"trace[340691470] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:348; }","duration":"247.890861ms","start":"2026-08-19T20:27:32.10687Z","end":"2026-08-19T20:27:32.354761Z","steps":["trace[340691470] 'read index received' (duration: 106.121243ms)","trace[340691470] 'applied index is now lower than readState.Index' (duration: 141.768698ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:32.354798Z","caller":"traceutil/trace.go:171","msg":"trace[1735816864] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"254.907708ms","start":"2026-08-19T20:27:32.099867Z","end":"2026-08-19T20:27:32.354775Z","steps":["trace[1735816864] 'process raft request' (duration: 113.097979ms)","trace[1735816864] 'compare' (duration: 141.335832ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:32.354926Z","caller":"traceutil/trace.go:171","msg":"trace[476961054] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"251.994088ms","start":"2026-08-19T20:27:32.102911Z","end":"2026-08-19T20:27:32.354905Z","steps":["trace[476961054] 'process raft request' (duration: 251.798555ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:32.354942Z","caller":"traceutil/trace.go:171","msg":"trace[1214880661] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"252.089799ms","start":"2026-08-19T20:27:32.102825Z","end":"2026-08-19T20:27:32.354915Z","steps":["trace[1214880661] 'process raft request' (duration: 251.808145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:32.355052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.341649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:27:32.355075Z","caller":"traceutil/trace.go:171","msg":"trace[1266465050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:337; }","duration":"117.40286ms","start":"2026-08-19T20:27:32.237665Z","end":"2026-08-19T20:27:32.355068Z","steps":["trace[1266465050] 'agreement among raft nodes before linearized reading' (duration: 117.351699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:32.355102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.223765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:27:32.355145Z","caller":"traceutil/trace.go:171","msg":"trace[69322806] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:337; }","duration":"248.297006ms","start":"2026-08-19T20:27:32.106835Z","end":"2026-08-19T20:27:32.355132Z","steps":["trace[69322806] 'agreement among raft nodes before linearized reading' (duration: 248.233145ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:32.538946Z","caller":"traceutil/trace.go:171","msg":"trace[824880196] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"136.282672ms","start":"2026-08-19T20:27:32.402638Z","end":"2026-08-19T20:27:32.538921Z","steps":["trace[824880196] 'read index received' (duration: 87.502534ms)","trace[824880196] 'applied index is now lower than readState.Index' (duration: 48.779268ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:32.539072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.412814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:27:32.539055Z","caller":"traceutil/trace.go:171","msg":"trace[966446782] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"172.269191ms","start":"2026-08-19T20:27:32.366744Z","end":"2026-08-19T20:27:32.539013Z","steps":["trace[966446782] 'process raft request' (duration: 123.429393ms)","trace[966446782] 'compare' (duration: 48.643476ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:32.539118Z","caller":"traceutil/trace.go:171","msg":"trace[791495939] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:339; }","duration":"136.512696ms","start":"2026-08-19T20:27:32.402595Z","end":"2026-08-19T20:27:32.539108Z","steps":["trace[791495939] 'agreement among raft nodes before linearized reading' (duration: 136.409644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:32.758269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.450861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:27:32.758528Z","caller":"traceutil/trace.go:171","msg":"trace[1438362277] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"139.229733ms","start":"2026-08-19T20:27:32.619287Z","end":"2026-08-19T20:27:32.758516Z","steps":["trace[1438362277] 'process raft request' (duration: 31.462817ms)","trace[1438362277] 'compare' (duration: 107.273109ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:32.75863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.846748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-19T20:27:32.758667Z","caller":"traceutil/trace.go:171","msg":"trace[518541259] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:347; }","duration":"133.910669ms","start":"2026-08-19T20:27:32.624745Z","end":"2026-08-19T20:27:32.758656Z","steps":["trace[518541259] 'agreement among raft nodes before linearized reading' (duration: 133.828867ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:32.758432Z","caller":"traceutil/trace.go:171","msg":"trace[5579001] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:360; }","duration":"133.639765ms","start":"2026-08-19T20:27:32.624776Z","end":"2026-08-19T20:27:32.758416Z","steps":["trace[5579001] 'read index received' (duration: 25.930511ms)","trace[5579001] 'applied index is now lower than readState.Index' (duration: 107.706444ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:33.243588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.161654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:27:33.243886Z","caller":"traceutil/trace.go:171","msg":"trace[1563537325] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"216.442697ms","start":"2026-08-19T20:27:33.027423Z","end":"2026-08-19T20:27:33.243866Z","steps":["trace[1563537325] 'process raft request' (duration: 113.942178ms)","trace[1563537325] 'compare' (duration: 101.998412ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:33.24388Z","caller":"traceutil/trace.go:171","msg":"trace[284101960] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"215.278979ms","start":"2026-08-19T20:27:33.028566Z","end":"2026-08-19T20:27:33.243845Z","steps":["trace[284101960] 'process raft request' (duration: 215.125197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:33.244201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.278774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-08-19T20:27:33.244278Z","caller":"traceutil/trace.go:171","msg":"trace[1045486064] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:357; }","duration":"169.426076ms","start":"2026-08-19T20:27:33.074839Z","end":"2026-08-19T20:27:33.244265Z","steps":["trace[1045486064] 'agreement among raft nodes before linearized reading' (duration: 169.219412ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:33.245282Z","caller":"traceutil/trace.go:171","msg":"trace[1018746776] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:369; }","duration":"168.858707ms","start":"2026-08-19T20:27:33.074881Z","end":"2026-08-19T20:27:33.24374Z","steps":["trace[1018746776] 'read index received' (duration: 66.49983ms)","trace[1018746776] 'applied index is now lower than readState.Index' (duration: 102.357877ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:33.245579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.983689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-19T20:27:33.245625Z","caller":"traceutil/trace.go:171","msg":"trace[444003755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:357; }","duration":"122.134711ms","start":"2026-08-19T20:27:33.123479Z","end":"2026-08-19T20:27:33.245613Z","steps":["trace[444003755] 'agreement among raft nodes before linearized reading' (duration: 121.910158ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:33.2464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.277729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:27:33.246441Z","caller":"traceutil/trace.go:171","msg":"trace[1325575213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:357; }","duration":"100.350629ms","start":"2026-08-19T20:27:33.14608Z","end":"2026-08-19T20:27:33.24643Z","steps":["trace[1325575213] 'agreement among raft nodes before linearized reading' (duration: 100.285348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:33.246767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.464944ms","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-08-19T20:27:33.246807Z","caller":"traceutil/trace.go:171","msg":"trace[500021951] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:357; }","duration":"122.526625ms","start":"2026-08-19T20:27:33.124271Z","end":"2026-08-19T20:27:33.246797Z","steps":["trace[500021951] 'agreement among raft nodes before linearized reading' (duration: 122.450214ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:33.710251Z","caller":"traceutil/trace.go:171","msg":"trace[154914898] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"108.393721ms","start":"2026-08-19T20:27:33.601828Z","end":"2026-08-19T20:27:33.710222Z","steps":["trace[154914898] 'process raft request' (duration: 65.121031ms)","trace[154914898] 'compare' (duration: 43.021467ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:33.710399Z","caller":"traceutil/trace.go:171","msg":"trace[919305416] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"108.403731ms","start":"2026-08-19T20:27:33.60197Z","end":"2026-08-19T20:27:33.710374Z","steps":["trace[919305416] 'process raft request' (duration: 108.139918ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:33.710261Z","caller":"traceutil/trace.go:171","msg":"trace[730242440] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"107.703442ms","start":"2026-08-19T20:27:33.602541Z","end":"2026-08-19T20:27:33.710244Z","steps":["trace[730242440] 'process raft request' (duration: 107.623901ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:33.935896Z","caller":"traceutil/trace.go:171","msg":"trace[72268246] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"141.025732ms","start":"2026-08-19T20:27:33.794844Z","end":"2026-08-19T20:27:33.93587Z","steps":["trace[72268246] 'process raft request' (duration: 140.965181ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:33.936058Z","caller":"traceutil/trace.go:171","msg":"trace[913036635] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"142.264609ms","start":"2026-08-19T20:27:33.793691Z","end":"2026-08-19T20:27:33.935956Z","steps":["trace[913036635] 'process raft request' (duration: 142.018346ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:33.935901Z","caller":"traceutil/trace.go:171","msg":"trace[442438634] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"148.288023ms","start":"2026-08-19T20:27:33.787569Z","end":"2026-08-19T20:27:33.935857Z","steps":["trace[442438634] 'process raft request' (duration: 82.868737ms)","trace[442438634] 'compare' (duration: 65.105481ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:33.936509Z","caller":"traceutil/trace.go:171","msg":"trace[742273522] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:391; }","duration":"111.250111ms","start":"2026-08-19T20:27:33.825221Z","end":"2026-08-19T20:27:33.936471Z","steps":["trace[742273522] 'read index received' (duration: 45.226676ms)","trace[742273522] 'applied index is now lower than readState.Index' (duration: 66.022545ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:33.93654Z","caller":"traceutil/trace.go:171","msg":"trace[1352597328] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"139.060055ms","start":"2026-08-19T20:27:33.797462Z","end":"2026-08-19T20:27:33.936522Z","steps":["trace[1352597328] 'process raft request' (duration: 138.866003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:33.936729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.491044ms","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-08-19T20:27:33.936813Z","caller":"traceutil/trace.go:171","msg":"trace[107270934] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:381; }","duration":"111.585325ms","start":"2026-08-19T20:27:33.825213Z","end":"2026-08-19T20:27:33.936798Z","steps":["trace[107270934] 'agreement among raft nodes before linearized reading' (duration: 111.376102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:36.699707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.981941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:27:36.699807Z","caller":"traceutil/trace.go:171","msg":"trace[1800556257] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"535.255297ms","start":"2026-08-19T20:27:36.164537Z","end":"2026-08-19T20:27:36.699792Z","steps":["trace[1800556257] 'read index received' (duration: 177.085084ms)","trace[1800556257] 'applied index is now lower than readState.Index' (duration: 358.168903ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:36.700009Z","caller":"traceutil/trace.go:171","msg":"trace[1423499225] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"1.003806388s","start":"2026-08-19T20:27:35.69619Z","end":"2026-08-19T20:27:36.699996Z","steps":["trace[1423499225] 'process raft request' (duration: 645.456702ms)","trace[1423499225] 'compare' (duration: 357.881319ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:36.700079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:35.696169Z","time spent":"1.003877518s","remote":"127.0.0.1:59096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:27:36.700285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.218051ms","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-08-19T20:27:36.700366Z","caller":"traceutil/trace.go:171","msg":"trace[1615755506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"328.340893ms","start":"2026-08-19T20:27:36.372004Z","end":"2026-08-19T20:27:36.700345Z","steps":["trace[1615755506] 'agreement among raft nodes before linearized reading' (duration: 328.170061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:36.700429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:36.371985Z","time spent":"328.412854ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-19T20:27:36.700637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.46153ms","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-08-19T20:27:36.700675Z","caller":"traceutil/trace.go:171","msg":"trace[2065011958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"116.532332ms","start":"2026-08-19T20:27:36.584131Z","end":"2026-08-19T20:27:36.700664Z","steps":["trace[2065011958] 'agreement among raft nodes before linearized reading' (duration: 116.470131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:36.700654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.103659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4325"} {"level":"info","ts":"2026-08-19T20:27:36.700725Z","caller":"traceutil/trace.go:171","msg":"trace[1479885499] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:408; }","duration":"536.22839ms","start":"2026-08-19T20:27:36.164479Z","end":"2026-08-19T20:27:36.700707Z","steps":["trace[1479885499] 'agreement among raft nodes before linearized reading' (duration: 536.074848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:36.700782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:36.164449Z","time spent":"536.324542ms","remote":"127.0.0.1:58808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-08-19T20:27:37.474935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.037245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:27:37.475138Z","caller":"traceutil/trace.go:171","msg":"trace[163717370] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"769.32032ms","start":"2026-08-19T20:27:36.705798Z","end":"2026-08-19T20:27:37.475118Z","steps":["trace[163717370] 'process raft request' (duration: 138.941ms)","trace[163717370] 'compare' (duration: 629.934294ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:37.475246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:36.705778Z","time spent":"769.432071ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:27:37.651363Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527199258617731,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:27:38.674551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.479539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:27:38.674827Z","caller":"traceutil/trace.go:171","msg":"trace[555276569] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:424; }","duration":"1.527719857s","start":"2026-08-19T20:27:37.147086Z","end":"2026-08-19T20:27:38.674806Z","steps":["trace[555276569] 'read index received' (duration: 593.924614ms)","trace[555276569] 'applied index is now lower than readState.Index' (duration: 933.793933ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:27:38.674942Z","caller":"traceutil/trace.go:171","msg":"trace[111541645] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.968868977s","start":"2026-08-19T20:27:36.706057Z","end":"2026-08-19T20:27:38.674926Z","steps":["trace[111541645] 'process raft request' (duration: 1.034921961s)","trace[111541645] 'compare' (duration: 933.296237ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:38.67506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:36.706046Z","time spent":"1.968933068s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:27:38.701007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.432635184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-19T20:27:38.701245Z","caller":"traceutil/trace.go:171","msg":"trace[529639739] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.993425053s","start":"2026-08-19T20:27:36.707712Z","end":"2026-08-19T20:27:38.701137Z","steps":["trace[529639739] 'process raft request' (duration: 1.966983301s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:38.701383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:36.707702Z","time spent":"1.993606006s","remote":"127.0.0.1:58808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:27:38.70105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256205343s","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-08-19T20:27:38.701217Z","caller":"traceutil/trace.go:171","msg":"trace[1534040351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"1.432879557s","start":"2026-08-19T20:27:37.26832Z","end":"2026-08-19T20:27:38.701199Z","steps":["trace[1534040351] 'agreement among raft nodes before linearized reading' (duration: 1.432603493s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:38.701497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:37.268296Z","time spent":"1.433191061s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-19T20:27:38.701479Z","caller":"traceutil/trace.go:171","msg":"trace[1481234811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:411; }","duration":"1.256652071s","start":"2026-08-19T20:27:37.444805Z","end":"2026-08-19T20:27:38.701457Z","steps":["trace[1481234811] 'agreement among raft nodes before linearized reading' (duration: 1.256221694s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:38.701602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:37.444787Z","time spent":"1.256763242s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T20:27:38.70105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.692048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-19T20:27:38.701664Z","caller":"traceutil/trace.go:171","msg":"trace[578812029] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:411; }","duration":"847.333958ms","start":"2026-08-19T20:27:37.854309Z","end":"2026-08-19T20:27:38.701643Z","steps":["trace[578812029] 'agreement among raft nodes before linearized reading' (duration: 846.656618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:38.675055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.527911299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T20:27:38.701793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:37.854294Z","time spent":"847.46706ms","remote":"127.0.0.1:58622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.201\" "} {"level":"info","ts":"2026-08-19T20:27:38.701834Z","caller":"traceutil/trace.go:171","msg":"trace[1148512161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"1.554743098s","start":"2026-08-19T20:27:37.147047Z","end":"2026-08-19T20:27:38.70179Z","steps":["trace[1148512161] 'agreement among raft nodes before linearized reading' (duration: 1.52788001s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:38.701902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:37.147028Z","time spent":"1.554862499s","remote":"127.0.0.1:58596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T20:27:39.205004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527199258617738,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:27:39.524174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.902704ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-19T20:27:39.524625Z","caller":"traceutil/trace.go:171","msg":"trace[935774915] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"818.812305ms","start":"2026-08-19T20:27:38.705793Z","end":"2026-08-19T20:27:39.524606Z","steps":["trace[935774915] 'process raft request' (duration: 818.566522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:39.524485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:38.703973Z","time spent":"820.504969ms","remote":"127.0.0.1:58622","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-19T20:27:39.524774Z","caller":"traceutil/trace.go:171","msg":"trace[1079092037] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:426; }","duration":"820.70001ms","start":"2026-08-19T20:27:38.704028Z","end":"2026-08-19T20:27:39.524728Z","steps":["trace[1079092037] 'read index received' (duration: 687.259069ms)","trace[1079092037] 'applied index is now lower than readState.Index' (duration: 133.439691ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:39.524908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.862753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4325"} {"level":"info","ts":"2026-08-19T20:27:39.525226Z","caller":"traceutil/trace.go:171","msg":"trace[1539253483] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:413; }","duration":"821.194607ms","start":"2026-08-19T20:27:38.704016Z","end":"2026-08-19T20:27:39.525211Z","steps":["trace[1539253483] 'agreement among raft nodes before linearized reading' (duration: 820.781572ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:39.525327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:38.704004Z","time spent":"821.284559ms","remote":"127.0.0.1:58808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-08-19T20:27:39.525425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.525951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:27:39.524902Z","caller":"traceutil/trace.go:171","msg":"trace[297180928] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"818.395321ms","start":"2026-08-19T20:27:38.706479Z","end":"2026-08-19T20:27:39.524875Z","steps":["trace[297180928] 'process raft request' (duration: 818.085756ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:39.525558Z","caller":"traceutil/trace.go:171","msg":"trace[1656208795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"818.659924ms","start":"2026-08-19T20:27:38.706861Z","end":"2026-08-19T20:27:39.525521Z","steps":["trace[1656208795] 'agreement among raft nodes before linearized reading' (duration: 818.518972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:39.525603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:38.706463Z","time spent":"819.07665ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:27:39.525625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:38.706851Z","time spent":"818.763135ms","remote":"127.0.0.1:58596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T20:27:39.525926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:38.705773Z","time spent":"819.192741ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:27:39.719493Z","caller":"traceutil/trace.go:171","msg":"trace[1224302739] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"192.344295ms","start":"2026-08-19T20:27:39.52712Z","end":"2026-08-19T20:27:39.719465Z","steps":["trace[1224302739] 'process raft request' (duration: 192.177613ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:39.801531Z","caller":"traceutil/trace.go:171","msg":"trace[887288789] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"268.942051ms","start":"2026-08-19T20:27:39.532553Z","end":"2026-08-19T20:27:39.801495Z","steps":["trace[887288789] 'process raft request' (duration: 268.632417ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:39.801734Z","caller":"traceutil/trace.go:171","msg":"trace[302789267] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"268.752878ms","start":"2026-08-19T20:27:39.532927Z","end":"2026-08-19T20:27:39.80168Z","steps":["trace[302789267] 'process raft request' (duration: 268.391184ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:39.948791Z","caller":"traceutil/trace.go:171","msg":"trace[525669117] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"127.941056ms","start":"2026-08-19T20:27:39.820824Z","end":"2026-08-19T20:27:39.948765Z","steps":["trace[525669117] 'process raft request' (duration: 127.779734ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:40.112508Z","caller":"traceutil/trace.go:171","msg":"trace[2013097322] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"154.170031ms","start":"2026-08-19T20:27:39.958308Z","end":"2026-08-19T20:27:40.112478Z","steps":["trace[2013097322] 'process raft request' (duration: 133.090305ms)","trace[2013097322] 'compare' (duration: 20.944585ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:40.447723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.240998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:27:40.447881Z","caller":"traceutil/trace.go:171","msg":"trace[1145500105] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"325.159708ms","start":"2026-08-19T20:27:40.122682Z","end":"2026-08-19T20:27:40.447842Z","steps":["trace[1145500105] 'process raft request' (duration: 124.721228ms)","trace[1145500105] 'compare' (duration: 200.120336ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:40.447977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.328435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:27:40.448029Z","caller":"traceutil/trace.go:171","msg":"trace[715491301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"301.406735ms","start":"2026-08-19T20:27:40.146612Z","end":"2026-08-19T20:27:40.448019Z","steps":["trace[715491301] 'agreement among raft nodes before linearized reading' (duration: 301.331914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:40.448062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:40.146598Z","time spent":"301.456006ms","remote":"127.0.0.1:58596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T20:27:40.447816Z","caller":"traceutil/trace.go:171","msg":"trace[1668543869] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"301.157032ms","start":"2026-08-19T20:27:40.146644Z","end":"2026-08-19T20:27:40.447801Z","steps":["trace[1668543869] 'read index received' (duration: 100.641931ms)","trace[1668543869] 'applied index is now lower than readState.Index' (duration: 200.514111ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:40.44816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:40.12266Z","time spent":"325.342541ms","remote":"127.0.0.1:58808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:27:45.509354Z","caller":"traceutil/trace.go:171","msg":"trace[1092913355] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"354.797629ms","start":"2026-08-19T20:27:45.154514Z","end":"2026-08-19T20:27:45.509312Z","steps":["trace[1092913355] 'read index received' (duration: 354.603337ms)","trace[1092913355] 'applied index is now lower than readState.Index' (duration: 193.422µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:27:45.509473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.945202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:27:45.50953Z","caller":"traceutil/trace.go:171","msg":"trace[40769568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:432; }","duration":"355.030543ms","start":"2026-08-19T20:27:45.154467Z","end":"2026-08-19T20:27:45.509498Z","steps":["trace[40769568] 'agreement among raft nodes before linearized reading' (duration: 354.955452ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:27:45.509531Z","caller":"traceutil/trace.go:171","msg":"trace[1211893205] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"784.529997ms","start":"2026-08-19T20:27:44.724865Z","end":"2026-08-19T20:27:45.509395Z","steps":["trace[1211893205] 'process raft request' (duration: 784.303814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:27:45.50962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:45.154451Z","time spent":"355.138494ms","remote":"127.0.0.1:58596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T20:27:45.509816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:44.724843Z","time spent":"784.879451ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:27:56.01872Z","caller":"traceutil/trace.go:171","msg":"trace[1270268977] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"117.219417ms","start":"2026-08-19T20:27:55.901471Z","end":"2026-08-19T20:27:56.01869Z","steps":["trace[1270268977] 'process raft request' (duration: 117.055715ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:00.370837Z","caller":"traceutil/trace.go:171","msg":"trace[479258435] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"335.24318ms","start":"2026-08-19T20:28:00.035569Z","end":"2026-08-19T20:28:00.370812Z","steps":["trace[479258435] 'read index received' (duration: 238.401922ms)","trace[479258435] 'applied index is now lower than readState.Index' (duration: 96.840288ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:00.370953Z","caller":"traceutil/trace.go:171","msg":"trace[79650015] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"657.640286ms","start":"2026-08-19T20:27:59.713276Z","end":"2026-08-19T20:28:00.370916Z","steps":["trace[79650015] 'process raft request' (duration: 560.732037ms)","trace[79650015] 'compare' (duration: 96.682046ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:00.371077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.503103ms","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-08-19T20:28:00.371115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:27:59.713251Z","time spent":"657.787929ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:28:00.371116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.01121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:00.371159Z","caller":"traceutil/trace.go:171","msg":"trace[711318021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"335.628605ms","start":"2026-08-19T20:28:00.03551Z","end":"2026-08-19T20:28:00.371138Z","steps":["trace[711318021] 'agreement among raft nodes before linearized reading' (duration: 335.490343ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:00.371188Z","caller":"traceutil/trace.go:171","msg":"trace[1748258456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"226.0719ms","start":"2026-08-19T20:28:00.14508Z","end":"2026-08-19T20:28:00.371152Z","steps":["trace[1748258456] 'agreement among raft nodes before linearized reading' (duration: 225.985509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:00.371206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:00.035492Z","time spent":"335.705426ms","remote":"127.0.0.1:58884","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-08-19T20:28:06.761629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.328972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:28:06.761738Z","caller":"traceutil/trace.go:171","msg":"trace[1015201067] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"298.612851ms","start":"2026-08-19T20:28:06.463107Z","end":"2026-08-19T20:28:06.761719Z","steps":["trace[1015201067] 'process raft request' (duration: 157.099417ms)","trace[1015201067] 'compare' (duration: 141.17682ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:11.277711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527199258618050,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:28:11.778299Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527199258618050,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:28:11.970104Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.332958088s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T20:28:11.970425Z","caller":"traceutil/trace.go:171","msg":"trace[1877677405] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.333367933s","start":"2026-08-19T20:28:10.637037Z","end":"2026-08-19T20:28:11.970404Z","steps":["trace[1877677405] 'process raft request' (duration: 1.333240861s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:11.970538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:10.637012Z","time spent":"1.333468935s","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:12.005945Z","caller":"traceutil/trace.go:171","msg":"trace[1510139885] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.364387262s","start":"2026-08-19T20:28:10.641523Z","end":"2026-08-19T20:28:12.005911Z","steps":["trace[1510139885] 'process raft request' (duration: 1.364138029s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.006085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:10.641496Z","time spent":"1.364525173s","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:12.006142Z","caller":"traceutil/trace.go:171","msg":"trace[756283868] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.36120145s","start":"2026-08-19T20:28:10.644923Z","end":"2026-08-19T20:28:12.006124Z","steps":["trace[756283868] 'process raft request' (duration: 1.360917607s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.006224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:10.644915Z","time spent":"1.361278711s","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:12.006275Z","caller":"traceutil/trace.go:171","msg":"trace[505458616] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.34957822s","start":"2026-08-19T20:28:10.656679Z","end":"2026-08-19T20:28:12.006258Z","steps":["trace[505458616] 'process raft request' (duration: 1.349253636s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.00637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:10.656659Z","time spent":"1.349667161s","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:12.007347Z","caller":"traceutil/trace.go:171","msg":"trace[743911923] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.330589686s","start":"2026-08-19T20:28:10.676736Z","end":"2026-08-19T20:28:12.007326Z","steps":["trace[743911923] 'process raft request' (duration: 1.330190211s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.007441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:10.676716Z","time spent":"1.330685247s","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:12.007492Z","caller":"traceutil/trace.go:171","msg":"trace[164585096] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.349100605s","start":"2026-08-19T20:28:10.658376Z","end":"2026-08-19T20:28:12.007476Z","steps":["trace[164585096] 'process raft request' (duration: 1.348209724s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.007552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:10.658358Z","time spent":"1.349173736s","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:12.007618Z","caller":"traceutil/trace.go:171","msg":"trace[1059159971] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.343758886s","start":"2026-08-19T20:28:10.663853Z","end":"2026-08-19T20:28:12.007612Z","steps":["trace[1059159971] 'process raft request' (duration: 1.342984686s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.00765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:10.663833Z","time spent":"1.343803527s","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:12.007657Z","caller":"traceutil/trace.go:171","msg":"trace[1343703272] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.330842589s","start":"2026-08-19T20:28:10.676796Z","end":"2026-08-19T20:28:12.007639Z","steps":["trace[1343703272] 'process raft request' (duration: 1.330207901s)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:12.007701Z","caller":"traceutil/trace.go:171","msg":"trace[1653472778] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.33086908s","start":"2026-08-19T20:28:10.676825Z","end":"2026-08-19T20:28:12.007694Z","steps":["trace[1653472778] 'process raft request' (duration: 1.330250442s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.007729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:10.67682Z","time spent":"1.33089891s","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T20:28:12.007799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:10.676771Z","time spent":"1.330926831s","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:12.008031Z","caller":"traceutil/trace.go:171","msg":"trace[319036908] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.319689226s","start":"2026-08-19T20:28:10.688315Z","end":"2026-08-19T20:28:12.008004Z","steps":["trace[319036908] 'process raft request' (duration: 1.318839305s)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:12.008148Z","caller":"traceutil/trace.go:171","msg":"trace[1574060997] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:577; }","duration":"1.230915652s","start":"2026-08-19T20:28:10.777223Z","end":"2026-08-19T20:28:12.008139Z","steps":["trace[1574060997] 'read index received' (duration: 1.193145646s)","trace[1574060997] 'applied index is now lower than readState.Index' (duration: 37.769126ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:12.00815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:10.688297Z","time spent":"1.319782797s","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:12.008203Z","caller":"traceutil/trace.go:171","msg":"trace[2004496052] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.280188416s","start":"2026-08-19T20:28:10.728008Z","end":"2026-08-19T20:28:12.008196Z","steps":["trace[2004496052] 'process raft request' (duration: 1.279828612s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.008239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:10.727986Z","time spent":"1.280231828s","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:12.008294Z","caller":"traceutil/trace.go:171","msg":"trace[870229052] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.196321085s","start":"2026-08-19T20:28:10.811967Z","end":"2026-08-19T20:28:12.008288Z","steps":["trace[870229052] 'process raft request' (duration: 1.195982201s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.008323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:10.81194Z","time spent":"1.196372387s","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:12.008333Z","caller":"traceutil/trace.go:171","msg":"trace[99402586] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"174.420434ms","start":"2026-08-19T20:28:11.833895Z","end":"2026-08-19T20:28:12.008316Z","steps":["trace[99402586] 'process raft request' (duration: 174.170481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.008406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231188495s","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-08-19T20:28:12.008431Z","caller":"traceutil/trace.go:171","msg":"trace[934486663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"1.231270066s","start":"2026-08-19T20:28:10.777155Z","end":"2026-08-19T20:28:12.008425Z","steps":["trace[934486663] 'agreement among raft nodes before linearized reading' (duration: 1.231209425s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.00845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:10.777143Z","time spent":"1.231302896s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-19T20:28:12.017445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.83435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:12.017489Z","caller":"traceutil/trace.go:171","msg":"trace[11575532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"136.92031ms","start":"2026-08-19T20:28:11.88056Z","end":"2026-08-19T20:28:12.01748Z","steps":["trace[11575532] 'agreement among raft nodes before linearized reading' (duration: 136.840269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.017704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.917288ms","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-08-19T20:28:12.017738Z","caller":"traceutil/trace.go:171","msg":"trace[1484535847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"145.99444ms","start":"2026-08-19T20:28:11.871737Z","end":"2026-08-19T20:28:12.017731Z","steps":["trace[1484535847] 'agreement among raft nodes before linearized reading' (duration: 145.888938ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.017837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.612592ms","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-08-19T20:28:12.017967Z","caller":"traceutil/trace.go:171","msg":"trace[875289622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"214.780194ms","start":"2026-08-19T20:28:11.803169Z","end":"2026-08-19T20:28:12.01795Z","steps":["trace[875289622] 'agreement among raft nodes before linearized reading' (duration: 214.102405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.279232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.37806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:28:12.279343Z","caller":"traceutil/trace.go:171","msg":"trace[993224441] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"194.587991ms","start":"2026-08-19T20:28:12.084737Z","end":"2026-08-19T20:28:12.279325Z","steps":["trace[993224441] 'process raft request' (duration: 94.033609ms)","trace[993224441] 'compare' (duration: 100.233678ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:12.279819Z","caller":"traceutil/trace.go:171","msg":"trace[1320482841] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"192.495234ms","start":"2026-08-19T20:28:12.087286Z","end":"2026-08-19T20:28:12.279781Z","steps":["trace[1320482841] 'process raft request' (duration: 192.290081ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:12.279969Z","caller":"traceutil/trace.go:171","msg":"trace[1964287977] transaction","detail":"{read_only:false; number_of_response:1; response_revision:584; }","duration":"192.582025ms","start":"2026-08-19T20:28:12.087349Z","end":"2026-08-19T20:28:12.279931Z","steps":["trace[1964287977] 'process raft request' (duration: 192.310951ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:12.280017Z","caller":"traceutil/trace.go:171","msg":"trace[1187744990] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"192.391563ms","start":"2026-08-19T20:28:12.087598Z","end":"2026-08-19T20:28:12.27999Z","steps":["trace[1187744990] 'process raft request' (duration: 192.113589ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:12.28012Z","caller":"traceutil/trace.go:171","msg":"trace[716939462] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"191.946296ms","start":"2026-08-19T20:28:12.088133Z","end":"2026-08-19T20:28:12.280079Z","steps":["trace[716939462] 'process raft request' (duration: 191.795354ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:12.28023Z","caller":"traceutil/trace.go:171","msg":"trace[494566230] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"192.121108ms","start":"2026-08-19T20:28:12.0881Z","end":"2026-08-19T20:28:12.280221Z","steps":["trace[494566230] 'process raft request' (duration: 191.783124ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:12.280265Z","caller":"traceutil/trace.go:171","msg":"trace[814586104] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"150.858639ms","start":"2026-08-19T20:28:12.129391Z","end":"2026-08-19T20:28:12.280249Z","steps":["trace[814586104] 'process raft request' (duration: 150.589325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:12.280415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.570936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:12.28055Z","caller":"traceutil/trace.go:171","msg":"trace[182424984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"133.678467ms","start":"2026-08-19T20:28:12.146775Z","end":"2026-08-19T20:28:12.280454Z","steps":["trace[182424984] 'agreement among raft nodes before linearized reading' (duration: 133.553646ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:12.294633Z","caller":"traceutil/trace.go:171","msg":"trace[782667496] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:610; }","duration":"133.240872ms","start":"2026-08-19T20:28:12.146837Z","end":"2026-08-19T20:28:12.280078Z","steps":["trace[782667496] 'read index received' (duration: 32.023531ms)","trace[782667496] 'applied index is now lower than readState.Index' (duration: 101.216491ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:12.529562Z","caller":"traceutil/trace.go:171","msg":"trace[560905325] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"233.283447ms","start":"2026-08-19T20:28:12.296259Z","end":"2026-08-19T20:28:12.529542Z","steps":["trace[560905325] 'process raft request' (duration: 232.708309ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:12.530085Z","caller":"traceutil/trace.go:171","msg":"trace[710488981] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"230.569662ms","start":"2026-08-19T20:28:12.299499Z","end":"2026-08-19T20:28:12.530068Z","steps":["trace[710488981] 'process raft request' (duration: 229.953204ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:12.530244Z","caller":"traceutil/trace.go:171","msg":"trace[1087829512] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"173.894354ms","start":"2026-08-19T20:28:12.356332Z","end":"2026-08-19T20:28:12.530226Z","steps":["trace[1087829512] 'process raft request' (duration: 173.328637ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:12.529362Z","caller":"traceutil/trace.go:171","msg":"trace[1781221748] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"234.320731ms","start":"2026-08-19T20:28:12.294829Z","end":"2026-08-19T20:28:12.52915Z","steps":["trace[1781221748] 'process raft request' (duration: 212.860605ms)","trace[1781221748] 'compare' (duration: 21.01224ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:12.53037Z","caller":"traceutil/trace.go:171","msg":"trace[1742689464] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"194.332957ms","start":"2026-08-19T20:28:12.336004Z","end":"2026-08-19T20:28:12.530337Z","steps":["trace[1742689464] 'process raft request' (duration: 193.546027ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:12.740501Z","caller":"traceutil/trace.go:171","msg":"trace[2100467468] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"138.112245ms","start":"2026-08-19T20:28:12.602356Z","end":"2026-08-19T20:28:12.740469Z","steps":["trace[2100467468] 'process raft request' (duration: 49.174163ms)","trace[2100467468] 'compare' (duration: 88.672458ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:12.740898Z","caller":"traceutil/trace.go:171","msg":"trace[1291547430] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"114.194968ms","start":"2026-08-19T20:28:12.626683Z","end":"2026-08-19T20:28:12.740878Z","steps":["trace[1291547430] 'process raft request' (duration: 114.027825ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:12.740927Z","caller":"traceutil/trace.go:171","msg":"trace[705800956] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"137.667229ms","start":"2026-08-19T20:28:12.603243Z","end":"2026-08-19T20:28:12.740911Z","steps":["trace[705800956] 'process raft request' (duration: 137.161293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:15.632792Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.074203011s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T20:28:15.633336Z","caller":"traceutil/trace.go:171","msg":"trace[2033198361] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"486.773158ms","start":"2026-08-19T20:28:15.146539Z","end":"2026-08-19T20:28:15.633312Z","steps":["trace[2033198361] 'read index received' (duration: 486.435464ms)","trace[2033198361] 'applied index is now lower than readState.Index' (duration: 336.544µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:15.633605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.570338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:15.633669Z","caller":"traceutil/trace.go:171","msg":"trace[318791327] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:639; }","duration":"240.68167ms","start":"2026-08-19T20:28:15.392966Z","end":"2026-08-19T20:28:15.633647Z","steps":["trace[318791327] 'agreement among raft nodes before linearized reading' (duration: 240.586029ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:15.633652Z","caller":"traceutil/trace.go:171","msg":"trace[366135182] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.07496713s","start":"2026-08-19T20:28:14.558486Z","end":"2026-08-19T20:28:15.633453Z","steps":["trace[366135182] 'process raft request' (duration: 1.074522965s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:15.633792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.154462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:15.633868Z","caller":"traceutil/trace.go:171","msg":"trace[299074781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"487.349435ms","start":"2026-08-19T20:28:15.146499Z","end":"2026-08-19T20:28:15.633849Z","steps":["trace[299074781] 'agreement among raft nodes before linearized reading' (duration: 487.145233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:15.633918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:15.146479Z","time spent":"487.429746ms","remote":"127.0.0.1:58596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T20:28:15.634136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:14.55847Z","time spent":"1.075375475s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:28:15.803587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.538527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:15.803678Z","caller":"traceutil/trace.go:171","msg":"trace[1590942578] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:639; }","duration":"150.666898ms","start":"2026-08-19T20:28:15.652989Z","end":"2026-08-19T20:28:15.803656Z","steps":["trace[1590942578] 'range keys from in-memory index tree' (duration: 150.409445ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:16.17247Z","caller":"traceutil/trace.go:171","msg":"trace[418929348] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"112.950464ms","start":"2026-08-19T20:28:16.05949Z","end":"2026-08-19T20:28:16.172441Z","steps":["trace[418929348] 'process raft request' (duration: 84.089234ms)","trace[418929348] 'compare' (duration: 28.672937ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:16.172803Z","caller":"traceutil/trace.go:171","msg":"trace[382556573] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"113.293478ms","start":"2026-08-19T20:28:16.059491Z","end":"2026-08-19T20:28:16.172785Z","steps":["trace[382556573] 'process raft request' (duration: 112.890693ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:16.354769Z","caller":"traceutil/trace.go:171","msg":"trace[473547374] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"152.106624ms","start":"2026-08-19T20:28:16.202635Z","end":"2026-08-19T20:28:16.354742Z","steps":["trace[473547374] 'process raft request' (duration: 123.63687ms)","trace[473547374] 'compare' (duration: 27.547312ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:16.948462Z","caller":"traceutil/trace.go:171","msg":"trace[802747658] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"150.445922ms","start":"2026-08-19T20:28:16.797986Z","end":"2026-08-19T20:28:16.948432Z","steps":["trace[802747658] 'process raft request' (duration: 149.722712ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:17.229178Z","caller":"traceutil/trace.go:171","msg":"trace[1095420855] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"220.97615ms","start":"2026-08-19T20:28:17.008159Z","end":"2026-08-19T20:28:17.229135Z","steps":["trace[1095420855] 'process raft request' (duration: 170.404184ms)","trace[1095420855] 'compare' (duration: 50.365633ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:17.852787Z","caller":"traceutil/trace.go:171","msg":"trace[1572320552] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"215.682682ms","start":"2026-08-19T20:28:17.637049Z","end":"2026-08-19T20:28:17.852732Z","steps":["trace[1572320552] 'read index received' (duration: 206.954181ms)","trace[1572320552] 'applied index is now lower than readState.Index' (duration: 8.727131ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:17.852854Z","caller":"traceutil/trace.go:171","msg":"trace[1664046522] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"219.211028ms","start":"2026-08-19T20:28:17.633609Z","end":"2026-08-19T20:28:17.85282Z","steps":["trace[1664046522] 'process raft request' (duration: 211.416518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:17.852977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.904935ms","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-08-19T20:28:17.853014Z","caller":"traceutil/trace.go:171","msg":"trace[1616682235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"215.996207ms","start":"2026-08-19T20:28:17.637006Z","end":"2026-08-19T20:28:17.853002Z","steps":["trace[1616682235] 'agreement among raft nodes before linearized reading' (duration: 215.851815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:18.161165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.799559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:28:18.161288Z","caller":"traceutil/trace.go:171","msg":"trace[1019560774] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"299.713221ms","start":"2026-08-19T20:28:17.861555Z","end":"2026-08-19T20:28:18.161268Z","steps":["trace[1019560774] 'process raft request' (duration: 87.707919ms)","trace[1019560774] 'compare' (duration: 211.518825ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:18.16826Z","caller":"traceutil/trace.go:171","msg":"trace[725388872] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:681; }","duration":"107.59395ms","start":"2026-08-19T20:28:18.060643Z","end":"2026-08-19T20:28:18.168237Z","steps":["trace[725388872] 'read index received' (duration: 107.368098ms)","trace[725388872] 'applied index is now lower than readState.Index' (duration: 224.992µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:18.168377Z","caller":"traceutil/trace.go:171","msg":"trace[1430246430] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"257.513333ms","start":"2026-08-19T20:28:17.910854Z","end":"2026-08-19T20:28:18.168367Z","steps":["trace[1430246430] 'process raft request' (duration: 257.343551ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:18.168446Z","caller":"traceutil/trace.go:171","msg":"trace[2024791514] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"274.711512ms","start":"2026-08-19T20:28:17.893704Z","end":"2026-08-19T20:28:18.168415Z","steps":["trace[2024791514] 'process raft request' (duration: 274.351957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:18.168655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.004645ms","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-08-19T20:28:18.168691Z","caller":"traceutil/trace.go:171","msg":"trace[1142157005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:652; }","duration":"108.094277ms","start":"2026-08-19T20:28:18.060586Z","end":"2026-08-19T20:28:18.16868Z","steps":["trace[1142157005] 'agreement among raft nodes before linearized reading' (duration: 107.927845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:18.427808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.0072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:28:18.427934Z","caller":"traceutil/trace.go:171","msg":"trace[688978841] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"206.254128ms","start":"2026-08-19T20:28:18.221667Z","end":"2026-08-19T20:28:18.427921Z","steps":["trace[688978841] 'process raft request' (duration: 206.213618ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:18.428053Z","caller":"traceutil/trace.go:171","msg":"trace[1742732083] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"208.124782ms","start":"2026-08-19T20:28:18.219887Z","end":"2026-08-19T20:28:18.428012Z","steps":["trace[1742732083] 'process raft request' (duration: 43.854468ms)","trace[1742732083] 'compare' (duration: 163.894779ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:19.291155Z","caller":"traceutil/trace.go:171","msg":"trace[2089735209] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"145.973637ms","start":"2026-08-19T20:28:19.145159Z","end":"2026-08-19T20:28:19.291133Z","steps":["trace[2089735209] 'read index received' (duration: 145.283638ms)","trace[2089735209] 'applied index is now lower than readState.Index' (duration: 688.999µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:19.291284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.106879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:19.291314Z","caller":"traceutil/trace.go:171","msg":"trace[1769050347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"146.17705ms","start":"2026-08-19T20:28:19.145127Z","end":"2026-08-19T20:28:19.291304Z","steps":["trace[1769050347] 'agreement among raft nodes before linearized reading' (duration: 146.106229ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:19.291513Z","caller":"traceutil/trace.go:171","msg":"trace[1838823743] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"159.066975ms","start":"2026-08-19T20:28:19.132435Z","end":"2026-08-19T20:28:19.291502Z","steps":["trace[1838823743] 'process raft request' (duration: 158.182974ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:19.719425Z","caller":"traceutil/trace.go:171","msg":"trace[1057579139] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"370.954362ms","start":"2026-08-19T20:28:19.348417Z","end":"2026-08-19T20:28:19.719371Z","steps":["trace[1057579139] 'process raft request' (duration: 370.79114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:19.719634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:19.348399Z","time spent":"371.179414ms","remote":"127.0.0.1:58962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:20.251919Z","caller":"traceutil/trace.go:171","msg":"trace[1717821487] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"105.43205ms","start":"2026-08-19T20:28:20.146447Z","end":"2026-08-19T20:28:20.251879Z","steps":["trace[1717821487] 'read index received' (duration: 96.487587ms)","trace[1717821487] 'applied index is now lower than readState.Index' (duration: 8.943773ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:20.252548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.100669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:20.252612Z","caller":"traceutil/trace.go:171","msg":"trace[1243478487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"106.21347ms","start":"2026-08-19T20:28:20.146384Z","end":"2026-08-19T20:28:20.252598Z","steps":["trace[1243478487] 'agreement among raft nodes before linearized reading' (duration: 106.103448ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:20.252791Z","caller":"traceutil/trace.go:171","msg":"trace[1043838329] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"122.74471ms","start":"2026-08-19T20:28:20.130037Z","end":"2026-08-19T20:28:20.252782Z","steps":["trace[1043838329] 'process raft request' (duration: 114.178351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:20.479719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.147421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:28:20.480026Z","caller":"traceutil/trace.go:171","msg":"trace[444080903] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"180.408562ms","start":"2026-08-19T20:28:20.299581Z","end":"2026-08-19T20:28:20.47999Z","steps":["trace[444080903] 'process raft request' (duration: 180.276141ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:20.480182Z","caller":"traceutil/trace.go:171","msg":"trace[2016513556] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"223.842455ms","start":"2026-08-19T20:28:20.25633Z","end":"2026-08-19T20:28:20.480172Z","steps":["trace[2016513556] 'process raft request' (duration: 61.167287ms)","trace[2016513556] 'compare' (duration: 162.04009ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:20.480156Z","caller":"traceutil/trace.go:171","msg":"trace[279123115] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"223.425639ms","start":"2026-08-19T20:28:20.256656Z","end":"2026-08-19T20:28:20.480081Z","steps":["trace[279123115] 'process raft request' (duration: 223.153005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:20.990293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527199258618238,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:28:21.490992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527199258618238,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:28:21.992138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527199258618238,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:28:22.047802Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.564454875s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-19T20:28:22.406468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.368263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:28:22.406724Z","caller":"traceutil/trace.go:171","msg":"trace[622190845] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:694; }","duration":"1.916442117s","start":"2026-08-19T20:28:20.490263Z","end":"2026-08-19T20:28:22.406705Z","steps":["trace[622190845] 'read index received' (duration: 1.557765851s)","trace[622190845] 'applied index is now lower than readState.Index' (duration: 358.675436ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:22.406735Z","caller":"traceutil/trace.go:171","msg":"trace[1967127494] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.923421155s","start":"2026-08-19T20:28:20.483259Z","end":"2026-08-19T20:28:22.40668Z","steps":["trace[1967127494] 'process raft request' (duration: 1.564755488s)","trace[1967127494] 'compare' (duration: 358.186271ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:22.406824Z","caller":"traceutil/trace.go:171","msg":"trace[626163625] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.922628546s","start":"2026-08-19T20:28:20.484186Z","end":"2026-08-19T20:28:22.406814Z","steps":["trace[626163625] 'process raft request' (duration: 1.922394393s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:22.406902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:20.484167Z","time spent":"1.922691966s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:28:22.406904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:20.483213Z","time spent":"1.923609769s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:28:22.407122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.916850402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T20:28:22.407128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259764783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T20:28:22.407168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.195459ms","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-08-19T20:28:22.407176Z","caller":"traceutil/trace.go:171","msg":"trace[889489768] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:666; }","duration":"1.916934603s","start":"2026-08-19T20:28:20.490232Z","end":"2026-08-19T20:28:22.407166Z","steps":["trace[889489768] 'agreement among raft nodes before linearized reading' (duration: 1.916834372s)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:22.407204Z","caller":"traceutil/trace.go:171","msg":"trace[1737338632] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:666; }","duration":"829.34737ms","start":"2026-08-19T20:28:21.577846Z","end":"2026-08-19T20:28:22.407193Z","steps":["trace[1737338632] 'agreement among raft nodes before linearized reading' (duration: 829.282009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:22.407216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:20.490223Z","time spent":"1.916986683s","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-08-19T20:28:22.407245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:21.577826Z","time spent":"829.410761ms","remote":"127.0.0.1:59066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-19T20:28:22.40688Z","caller":"traceutil/trace.go:171","msg":"trace[1184518786] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"302.616616ms","start":"2026-08-19T20:28:22.104241Z","end":"2026-08-19T20:28:22.406858Z","steps":["trace[1184518786] 'process raft request' (duration: 302.434954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:22.407517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:22.104216Z","time spent":"303.254755ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:28:22.407767Z","caller":"traceutil/trace.go:171","msg":"trace[676005994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"1.259871084s","start":"2026-08-19T20:28:21.147307Z","end":"2026-08-19T20:28:22.407178Z","steps":["trace[676005994] 'agreement among raft nodes before linearized reading' (duration: 1.259631031s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:22.407887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:21.147284Z","time spent":"1.260531794s","remote":"127.0.0.1:58596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T20:28:23.090747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.566571ms","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-08-19T20:28:23.091072Z","caller":"traceutil/trace.go:171","msg":"trace[818334548] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:671; }","duration":"170.903175ms","start":"2026-08-19T20:28:22.920151Z","end":"2026-08-19T20:28:23.091054Z","steps":["trace[818334548] 'range keys from in-memory index tree' (duration: 170.553171ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:23.090949Z","caller":"traceutil/trace.go:171","msg":"trace[1501857786] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"376.569833ms","start":"2026-08-19T20:28:22.714353Z","end":"2026-08-19T20:28:23.090923Z","steps":["trace[1501857786] 'process raft request' (duration: 376.256129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:23.091649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:22.714328Z","time spent":"377.075659ms","remote":"127.0.0.1:58808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:28:24.370854Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.260325291s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T20:28:24.371993Z","caller":"traceutil/trace.go:171","msg":"trace[1601494166] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"1.550215715s","start":"2026-08-19T20:28:22.82175Z","end":"2026-08-19T20:28:24.371966Z","steps":["trace[1601494166] 'process raft request' (duration: 1.549590816s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:24.372259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:22.821722Z","time spent":"1.550342207s","remote":"127.0.0.1:58694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:28:24.547831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.912674ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-19T20:28:24.547939Z","caller":"traceutil/trace.go:171","msg":"trace[1076404713] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:703; }","duration":"401.706159ms","start":"2026-08-19T20:28:24.146214Z","end":"2026-08-19T20:28:24.54792Z","steps":["trace[1076404713] 'read index received' (duration: 225.455601ms)","trace[1076404713] 'applied index is now lower than readState.Index' (duration: 176.249168ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:24.548042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.822562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:24.548077Z","caller":"traceutil/trace.go:171","msg":"trace[1848259350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"401.889853ms","start":"2026-08-19T20:28:24.146178Z","end":"2026-08-19T20:28:24.548068Z","steps":["trace[1848259350] 'agreement among raft nodes before linearized reading' (duration: 401.819761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:24.548154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:24.146162Z","time spent":"401.948014ms","remote":"127.0.0.1:58596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T20:28:24.591126Z","caller":"traceutil/trace.go:171","msg":"trace[1689425260] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"157.275129ms","start":"2026-08-19T20:28:24.43383Z","end":"2026-08-19T20:28:24.591105Z","steps":["trace[1689425260] 'process raft request' (duration: 157.147527ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:24.61035Z","caller":"traceutil/trace.go:171","msg":"trace[982461687] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"115.640583ms","start":"2026-08-19T20:28:24.494691Z","end":"2026-08-19T20:28:24.610331Z","steps":["trace[982461687] 'process raft request' (duration: 115.38756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:24.61059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.227572ms","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-08-19T20:28:24.610654Z","caller":"traceutil/trace.go:171","msg":"trace[670877786] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:675; }","duration":"200.327104ms","start":"2026-08-19T20:28:24.410315Z","end":"2026-08-19T20:28:24.610642Z","steps":["trace[670877786] 'agreement among raft nodes before linearized reading' (duration: 200.178522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:24.610877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.667955ms","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-08-19T20:28:24.610926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.302468ms","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-08-19T20:28:24.610987Z","caller":"traceutil/trace.go:171","msg":"trace[976215989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"198.382109ms","start":"2026-08-19T20:28:24.412592Z","end":"2026-08-19T20:28:24.610974Z","steps":["trace[976215989] 'agreement among raft nodes before linearized reading' (duration: 198.202717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:24.611556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.380421ms","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-08-19T20:28:24.611592Z","caller":"traceutil/trace.go:171","msg":"trace[337013439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:675; }","duration":"116.823917ms","start":"2026-08-19T20:28:24.494145Z","end":"2026-08-19T20:28:24.610969Z","steps":["trace[337013439] 'agreement among raft nodes before linearized reading' (duration: 116.529423ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:24.611635Z","caller":"traceutil/trace.go:171","msg":"trace[809807133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:675; }","duration":"116.465852ms","start":"2026-08-19T20:28:24.495152Z","end":"2026-08-19T20:28:24.611618Z","steps":["trace[809807133] 'agreement among raft nodes before linearized reading' (duration: 115.577161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:26.647007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527199258618264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:28:27.147954Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527199258618264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:28:27.649047Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527199258618264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:28:27.658457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.917070373s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:28:27.658606Z","caller":"traceutil/trace.go:171","msg":"trace[1506179375] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"3.045174023s","start":"2026-08-19T20:28:24.613408Z","end":"2026-08-19T20:28:27.658582Z","steps":["trace[1506179375] 'process raft request' (duration: 127.906618ms)","trace[1506179375] 'compare' (duration: 2.916010499s)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:27.658691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:24.613389Z","time spent":"3.045265894s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:28:27.658883Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.917521609s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T20:28:27.659192Z","caller":"traceutil/trace.go:171","msg":"trace[562962113] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"3.043643664s","start":"2026-08-19T20:28:24.615523Z","end":"2026-08-19T20:28:27.659167Z","steps":["trace[562962113] 'process raft request' (duration: 3.043576493s)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:27.659218Z","caller":"traceutil/trace.go:171","msg":"trace[1336746837] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"3.043799006s","start":"2026-08-19T20:28:24.615384Z","end":"2026-08-19T20:28:27.659183Z","steps":["trace[1336746837] 'process raft request' (duration: 3.043598503s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:27.659341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:24.615514Z","time spent":"3.043750765s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:28:27.659379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:24.615361Z","time spent":"3.043932467s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:28:27.659395Z","caller":"traceutil/trace.go:171","msg":"trace[516096767] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"3.043796866s","start":"2026-08-19T20:28:24.615573Z","end":"2026-08-19T20:28:27.65937Z","steps":["trace[516096767] 'process raft request' (duration: 3.043558523s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:27.659532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:24.615567Z","time spent":"3.043894627s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:28:27.70739Z","caller":"traceutil/trace.go:171","msg":"trace[1118476637] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:708; }","duration":"1.560917462s","start":"2026-08-19T20:28:26.146451Z","end":"2026-08-19T20:28:27.707369Z","steps":["trace[1118476637] 'read index received' (duration: 1.512630103s)","trace[1118476637] 'applied index is now lower than readState.Index' (duration: 48.286479ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:27.707519Z","caller":"traceutil/trace.go:171","msg":"trace[2122164488] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"2.688043745s","start":"2026-08-19T20:28:25.019427Z","end":"2026-08-19T20:28:27.707471Z","steps":["trace[2122164488] 'process raft request' (duration: 2.687468608s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:27.707604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561037573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T20:28:27.707626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285923512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:27.707676Z","caller":"traceutil/trace.go:171","msg":"trace[537219086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"1.561241205s","start":"2026-08-19T20:28:26.146419Z","end":"2026-08-19T20:28:27.70766Z","steps":["trace[537219086] 'agreement among raft nodes before linearized reading' (duration: 1.561045163s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:27.707706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:25.019405Z","time spent":"2.688206447s","remote":"127.0.0.1:58694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:28:27.707788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.718659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-19T20:28:27.707756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:26.146401Z","time spent":"1.561345156s","remote":"127.0.0.1:58596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T20:28:27.707822Z","caller":"traceutil/trace.go:171","msg":"trace[1752150996] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:680; }","duration":"979.754849ms","start":"2026-08-19T20:28:26.72806Z","end":"2026-08-19T20:28:27.707815Z","steps":["trace[1752150996] 'agreement among raft nodes before linearized reading' (duration: 979.682758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:27.707866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:26.728003Z","time spent":"979.853771ms","remote":"127.0.0.1:58830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-08-19T20:28:27.707716Z","caller":"traceutil/trace.go:171","msg":"trace[2000770068] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:680; }","duration":"1.286067904s","start":"2026-08-19T20:28:26.421626Z","end":"2026-08-19T20:28:27.707694Z","steps":["trace[2000770068] 'agreement among raft nodes before linearized reading' (duration: 1.285915942s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:27.707992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:26.421608Z","time spent":"1.286368689s","remote":"127.0.0.1:58908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-19T20:28:29.375213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.215919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:29.375295Z","caller":"traceutil/trace.go:171","msg":"trace[582031017] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:696; }","duration":"146.350471ms","start":"2026-08-19T20:28:29.228928Z","end":"2026-08-19T20:28:29.375279Z","steps":["trace[582031017] 'count revisions from in-memory index tree' (duration: 146.139538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:29.375116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.01269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:29.375567Z","caller":"traceutil/trace.go:171","msg":"trace[366351252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"229.439045ms","start":"2026-08-19T20:28:29.146035Z","end":"2026-08-19T20:28:29.375474Z","steps":["trace[366351252] 'range keys from in-memory index tree' (duration: 228.921499ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:29.677398Z","caller":"traceutil/trace.go:171","msg":"trace[927243464] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"102.967234ms","start":"2026-08-19T20:28:29.574417Z","end":"2026-08-19T20:28:29.677384Z","steps":["trace[927243464] 'process raft request' (duration: 102.7311ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:29.677506Z","caller":"traceutil/trace.go:171","msg":"trace[1891300362] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"102.798843ms","start":"2026-08-19T20:28:29.574702Z","end":"2026-08-19T20:28:29.677501Z","steps":["trace[1891300362] 'process raft request' (duration: 102.548739ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:29.677662Z","caller":"traceutil/trace.go:171","msg":"trace[2069714043] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"102.832153ms","start":"2026-08-19T20:28:29.574824Z","end":"2026-08-19T20:28:29.677656Z","steps":["trace[2069714043] 'process raft request' (duration: 102.493099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:29.677746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.786648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:29.677768Z","caller":"traceutil/trace.go:171","msg":"trace[436488760] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"108.830228ms","start":"2026-08-19T20:28:29.568932Z","end":"2026-08-19T20:28:29.677762Z","steps":["trace[436488760] 'agreement among raft nodes before linearized reading' (duration: 108.771727ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:33.720013Z","caller":"traceutil/trace.go:171","msg":"trace[795513278] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"109.022685ms","start":"2026-08-19T20:28:33.610916Z","end":"2026-08-19T20:28:33.719938Z","steps":["trace[795513278] 'process raft request' (duration: 108.780772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:33.72006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.521593ms","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-08-19T20:28:33.720152Z","caller":"traceutil/trace.go:171","msg":"trace[1497781189] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:767; }","duration":"106.635035ms","start":"2026-08-19T20:28:33.613492Z","end":"2026-08-19T20:28:33.720127Z","steps":["trace[1497781189] 'agreement among raft nodes before linearized reading' (duration: 106.469373ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:33.720032Z","caller":"traceutil/trace.go:171","msg":"trace[1974995008] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:800; }","duration":"106.466663ms","start":"2026-08-19T20:28:33.613516Z","end":"2026-08-19T20:28:33.719983Z","steps":["trace[1974995008] 'read index received' (duration: 87.381294ms)","trace[1974995008] 'applied index is now lower than readState.Index' (duration: 19.038028ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:33.720615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.985079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:33.720694Z","caller":"traceutil/trace.go:171","msg":"trace[1031109272] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:767; }","duration":"107.06097ms","start":"2026-08-19T20:28:33.613604Z","end":"2026-08-19T20:28:33.720665Z","steps":["trace[1031109272] 'agreement among raft nodes before linearized reading' (duration: 106.748456ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:33.838883Z","caller":"traceutil/trace.go:171","msg":"trace[1855297660] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"104.624819ms","start":"2026-08-19T20:28:33.734232Z","end":"2026-08-19T20:28:33.838857Z","steps":["trace[1855297660] 'read index received' (duration: 102.755525ms)","trace[1855297660] 'applied index is now lower than readState.Index' (duration: 1.868314ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:33.838927Z","caller":"traceutil/trace.go:171","msg":"trace[2062194345] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"108.167395ms","start":"2026-08-19T20:28:33.730743Z","end":"2026-08-19T20:28:33.838911Z","steps":["trace[2062194345] 'process raft request' (duration: 106.311891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:33.839135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.487112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:33.839393Z","caller":"traceutil/trace.go:171","msg":"trace[491294995] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:773; }","duration":"110.768235ms","start":"2026-08-19T20:28:33.728612Z","end":"2026-08-19T20:28:33.83938Z","steps":["trace[491294995] 'agreement among raft nodes before linearized reading' (duration: 110.431031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:33.839266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.575071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:33.839676Z","caller":"traceutil/trace.go:171","msg":"trace[102883453] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:773; }","duration":"109.984396ms","start":"2026-08-19T20:28:33.729633Z","end":"2026-08-19T20:28:33.839618Z","steps":["trace[102883453] 'agreement among raft nodes before linearized reading' (duration: 109.334108ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:34.047504Z","caller":"traceutil/trace.go:171","msg":"trace[721077362] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"106.518122ms","start":"2026-08-19T20:28:33.940968Z","end":"2026-08-19T20:28:34.047486Z","steps":["trace[721077362] 'process raft request' (duration: 106.420861ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:34.047762Z","caller":"traceutil/trace.go:171","msg":"trace[394392738] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:813; }","duration":"106.486962ms","start":"2026-08-19T20:28:33.941268Z","end":"2026-08-19T20:28:34.047755Z","steps":["trace[394392738] 'read index received' (duration: 106.483882ms)","trace[394392738] 'applied index is now lower than readState.Index' (duration: 2.56µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:34.047855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.565113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:34.047882Z","caller":"traceutil/trace.go:171","msg":"trace[179142170] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:778; }","duration":"106.632044ms","start":"2026-08-19T20:28:33.941243Z","end":"2026-08-19T20:28:34.047875Z","steps":["trace[179142170] 'agreement among raft nodes before linearized reading' (duration: 106.555173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:34.072979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.481734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:34.073041Z","caller":"traceutil/trace.go:171","msg":"trace[1401957382] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:780; }","duration":"131.548775ms","start":"2026-08-19T20:28:33.941471Z","end":"2026-08-19T20:28:34.07302Z","steps":["trace[1401957382] 'agreement among raft nodes before linearized reading' (duration: 131.433684ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:34.073298Z","caller":"traceutil/trace.go:171","msg":"trace[854208166] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"129.517639ms","start":"2026-08-19T20:28:33.943774Z","end":"2026-08-19T20:28:34.073292Z","steps":["trace[854208166] 'process raft request' (duration: 128.896512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:34.074195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.439388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:34.074223Z","caller":"traceutil/trace.go:171","msg":"trace[705633708] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:780; }","duration":"109.5042ms","start":"2026-08-19T20:28:33.964712Z","end":"2026-08-19T20:28:34.074216Z","steps":["trace[705633708] 'agreement among raft nodes before linearized reading' (duration: 109.463099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:34.074295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.694321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:34.074314Z","caller":"traceutil/trace.go:171","msg":"trace[1378856049] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:780; }","duration":"109.723542ms","start":"2026-08-19T20:28:33.964586Z","end":"2026-08-19T20:28:34.07431Z","steps":["trace[1378856049] 'agreement among raft nodes before linearized reading' (duration: 109.696341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:34.074369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.901044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:34.074387Z","caller":"traceutil/trace.go:171","msg":"trace[311512138] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:780; }","duration":"109.930565ms","start":"2026-08-19T20:28:33.964452Z","end":"2026-08-19T20:28:34.074383Z","steps":["trace[311512138] 'agreement among raft nodes before linearized reading' (duration: 109.905344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:34.074505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.93366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-08-19T20:28:34.074525Z","caller":"traceutil/trace.go:171","msg":"trace[188391688] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:780; }","duration":"111.9663ms","start":"2026-08-19T20:28:33.962554Z","end":"2026-08-19T20:28:34.07452Z","steps":["trace[188391688] 'agreement among raft nodes before linearized reading' (duration: 111.881219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:34.074611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.917602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:34.074629Z","caller":"traceutil/trace.go:171","msg":"trace[431509440] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:780; }","duration":"132.950053ms","start":"2026-08-19T20:28:33.941675Z","end":"2026-08-19T20:28:34.074625Z","steps":["trace[431509440] 'agreement among raft nodes before linearized reading' (duration: 132.922572ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:37.301294Z","caller":"traceutil/trace.go:171","msg":"trace[1355913032] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"226.736566ms","start":"2026-08-19T20:28:37.074535Z","end":"2026-08-19T20:28:37.301271Z","steps":["trace[1355913032] 'process raft request' (duration: 174.354383ms)","trace[1355913032] 'compare' (duration: 52.210071ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:44.64861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527199258618624,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:28:45.1498Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527199258618624,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:28:45.576752Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.707938924s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T20:28:45.577788Z","caller":"traceutil/trace.go:171","msg":"trace[1399304691] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"1.748128034s","start":"2026-08-19T20:28:43.829638Z","end":"2026-08-19T20:28:45.577766Z","steps":["trace[1399304691] 'process raft request' (duration: 1.747745898s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:45.577932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:43.829613Z","time spent":"1.748245315s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:28:45.577981Z","caller":"traceutil/trace.go:171","msg":"trace[1145844276] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"1.744651399s","start":"2026-08-19T20:28:43.833315Z","end":"2026-08-19T20:28:45.577967Z","steps":["trace[1145844276] 'process raft request' (duration: 1.744606178s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:45.578065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:43.833298Z","time spent":"1.74472759s","remote":"127.0.0.1:58808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:28:45.578365Z","caller":"traceutil/trace.go:171","msg":"trace[2141172225] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"1.748636989s","start":"2026-08-19T20:28:43.829717Z","end":"2026-08-19T20:28:45.578354Z","steps":["trace[2141172225] 'process raft request' (duration: 1.748007211s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:45.578431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:43.8297Z","time spent":"1.748698699s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:28:45.578516Z","caller":"traceutil/trace.go:171","msg":"trace[1017086150] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"1.748759299s","start":"2026-08-19T20:28:43.829749Z","end":"2026-08-19T20:28:45.578508Z","steps":["trace[1017086150] 'process raft request' (duration: 1.748134122s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:45.578565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:43.829711Z","time spent":"1.748829931s","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:28:45.579616Z","caller":"traceutil/trace.go:171","msg":"trace[1390667950] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:883; }","duration":"1.431629731s","start":"2026-08-19T20:28:44.147964Z","end":"2026-08-19T20:28:45.579593Z","steps":["trace[1390667950] 'read index received' (duration: 1.431621521s)","trace[1390667950] 'applied index is now lower than readState.Index' (duration: 7.01µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:45.579738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431765013s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:45.579774Z","caller":"traceutil/trace.go:171","msg":"trace[2012915010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:845; }","duration":"1.431816293s","start":"2026-08-19T20:28:44.147948Z","end":"2026-08-19T20:28:45.579765Z","steps":["trace[2012915010] 'agreement among raft nodes before linearized reading' (duration: 1.431732022s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:45.579814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:44.14789Z","time spent":"1.431913955s","remote":"127.0.0.1:58596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T20:28:45.583275Z","caller":"traceutil/trace.go:171","msg":"trace[144778269] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"344.026095ms","start":"2026-08-19T20:28:45.239227Z","end":"2026-08-19T20:28:45.583253Z","steps":["trace[144778269] 'process raft request' (duration: 343.847493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:45.583398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:45.239207Z","time spent":"344.126817ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:28:59.440846Z","caller":"traceutil/trace.go:171","msg":"trace[1966836460] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"154.883275ms","start":"2026-08-19T20:28:59.285919Z","end":"2026-08-19T20:28:59.440802Z","steps":["trace[1966836460] 'process raft request' (duration: 154.5258ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:59.548822Z","caller":"traceutil/trace.go:171","msg":"trace[1885687103] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"103.200043ms","start":"2026-08-19T20:28:59.445596Z","end":"2026-08-19T20:28:59.548796Z","steps":["trace[1885687103] 'process raft request' (duration: 102.985291ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:12.305608Z","caller":"traceutil/trace.go:171","msg":"trace[1316834852] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1102; }","duration":"159.358323ms","start":"2026-08-19T20:29:12.146226Z","end":"2026-08-19T20:29:12.305584Z","steps":["trace[1316834852] 'read index received' (duration: 133.483958ms)","trace[1316834852] 'applied index is now lower than readState.Index' (duration: 25.873175ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:29:12.305645Z","caller":"traceutil/trace.go:171","msg":"trace[286918864] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"248.99849ms","start":"2026-08-19T20:29:12.056607Z","end":"2026-08-19T20:29:12.305606Z","steps":["trace[286918864] 'process raft request' (duration: 223.190966ms)","trace[286918864] 'compare' (duration: 25.616302ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:29:12.305762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.509685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:29:12.305826Z","caller":"traceutil/trace.go:171","msg":"trace[1948872093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"159.626856ms","start":"2026-08-19T20:29:12.146185Z","end":"2026-08-19T20:29:12.305812Z","steps":["trace[1948872093] 'agreement among raft nodes before linearized reading' (duration: 159.492485ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:12.306266Z","caller":"traceutil/trace.go:171","msg":"trace[749144217] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"242.174478ms","start":"2026-08-19T20:29:12.064066Z","end":"2026-08-19T20:29:12.30624Z","steps":["trace[749144217] 'process raft request' (duration: 241.467ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:14.252898Z","caller":"traceutil/trace.go:171","msg":"trace[1893251677] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"143.54895ms","start":"2026-08-19T20:29:14.109324Z","end":"2026-08-19T20:29:14.252873Z","steps":["trace[1893251677] 'process raft request' (duration: 143.379218ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:14.444397Z","caller":"traceutil/trace.go:171","msg":"trace[1237613321] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"132.682977ms","start":"2026-08-19T20:29:14.311694Z","end":"2026-08-19T20:29:14.444377Z","steps":["trace[1237613321] 'process raft request' (duration: 132.562046ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:14.446697Z","caller":"traceutil/trace.go:171","msg":"trace[357097961] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"103.404113ms","start":"2026-08-19T20:29:14.34327Z","end":"2026-08-19T20:29:14.446675Z","steps":["trace[357097961] 'process raft request' (duration: 103.269642ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:16.453248Z","caller":"traceutil/trace.go:171","msg":"trace[419400251] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"192.974145ms","start":"2026-08-19T20:29:16.260255Z","end":"2026-08-19T20:29:16.453229Z","steps":["trace[419400251] 'process raft request' (duration: 192.835873ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:16.521106Z","caller":"traceutil/trace.go:171","msg":"trace[1787031845] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1115; }","duration":"200.237283ms","start":"2026-08-19T20:29:16.320849Z","end":"2026-08-19T20:29:16.521086Z","steps":["trace[1787031845] 'read index received' (duration: 132.270111ms)","trace[1787031845] 'applied index is now lower than readState.Index' (duration: 67.966342ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:29:16.521217Z","caller":"traceutil/trace.go:171","msg":"trace[207188409] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"251.687665ms","start":"2026-08-19T20:29:16.269495Z","end":"2026-08-19T20:29:16.521183Z","steps":["trace[207188409] 'process raft request' (duration: 209.627936ms)","trace[207188409] 'compare' (duration: 41.798536ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:29:16.521715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.846131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T20:29:16.521755Z","caller":"traceutil/trace.go:171","msg":"trace[409000409] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1073; }","duration":"200.919542ms","start":"2026-08-19T20:29:16.320827Z","end":"2026-08-19T20:29:16.521747Z","steps":["trace[409000409] 'agreement among raft nodes before linearized reading' (duration: 200.397925ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:20.571704Z","caller":"traceutil/trace.go:171","msg":"trace[1805354170] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"100.924679ms","start":"2026-08-19T20:29:20.470756Z","end":"2026-08-19T20:29:20.571681Z","steps":["trace[1805354170] 'process raft request' (duration: 100.773977ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:34.58842Z","caller":"traceutil/trace.go:171","msg":"trace[901830619] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"261.22139ms","start":"2026-08-19T20:29:34.327171Z","end":"2026-08-19T20:29:34.588392Z","steps":["trace[901830619] 'process raft request' (duration: 261.036054ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:34.677247Z","caller":"traceutil/trace.go:171","msg":"trace[1059534686] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"349.752908ms","start":"2026-08-19T20:29:34.327457Z","end":"2026-08-19T20:29:34.67721Z","steps":["trace[1059534686] 'process raft request' (duration: 317.571124ms)","trace[1059534686] 'compare' (duration: 32.036908ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:29:34.677378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:34.327439Z","time spent":"349.875622ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:29:37.009269Z","caller":"traceutil/trace.go:171","msg":"trace[1359352793] linearizableReadLoop","detail":"{readStateIndex:1193; appliedIndex:1191; }","duration":"174.54636ms","start":"2026-08-19T20:29:36.834694Z","end":"2026-08-19T20:29:37.00924Z","steps":["trace[1359352793] 'read index received' (duration: 10.630406ms)","trace[1359352793] 'applied index is now lower than readState.Index' (duration: 163.914744ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:29:37.009346Z","caller":"traceutil/trace.go:171","msg":"trace[262893990] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"181.308027ms","start":"2026-08-19T20:29:36.82801Z","end":"2026-08-19T20:29:37.009318Z","steps":["trace[262893990] 'process raft request' (duration: 179.814133ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:37.00949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.774639ms","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-08-19T20:29:37.009539Z","caller":"traceutil/trace.go:171","msg":"trace[270421791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1143; }","duration":"174.863393ms","start":"2026-08-19T20:29:36.834659Z","end":"2026-08-19T20:29:37.009523Z","steps":["trace[270421791] 'agreement among raft nodes before linearized reading' (duration: 174.692856ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:39.019726Z","caller":"traceutil/trace.go:171","msg":"trace[1047667424] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1199; }","duration":"170.625149ms","start":"2026-08-19T20:29:38.849077Z","end":"2026-08-19T20:29:39.019702Z","steps":["trace[1047667424] 'read index received' (duration: 164.933994ms)","trace[1047667424] 'applied index is now lower than readState.Index' (duration: 5.690585ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:29:39.019765Z","caller":"traceutil/trace.go:171","msg":"trace[747067935] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"264.680565ms","start":"2026-08-19T20:29:38.755059Z","end":"2026-08-19T20:29:39.019739Z","steps":["trace[747067935] 'process raft request' (duration: 259.006251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:39.019873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.792645ms","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-08-19T20:29:39.019893Z","caller":"traceutil/trace.go:171","msg":"trace[938041212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1149; }","duration":"170.862537ms","start":"2026-08-19T20:29:38.849024Z","end":"2026-08-19T20:29:39.019886Z","steps":["trace[938041212] 'agreement among raft nodes before linearized reading' (duration: 170.758034ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:48.894295Z","caller":"traceutil/trace.go:171","msg":"trace[801994815] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"102.754954ms","start":"2026-08-19T20:29:48.791513Z","end":"2026-08-19T20:29:48.894268Z","steps":["trace[801994815] 'process raft request' (duration: 44.852877ms)","trace[801994815] 'compare' (duration: 57.722001ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:29:49.317879Z","caller":"traceutil/trace.go:171","msg":"trace[1081394874] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"152.424315ms","start":"2026-08-19T20:29:49.165417Z","end":"2026-08-19T20:29:49.317841Z","steps":["trace[1081394874] 'process raft request' (duration: 92.914021ms)","trace[1081394874] 'compare' (duration: 59.319538ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:29:49.318939Z","caller":"traceutil/trace.go:171","msg":"trace[1783218322] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"153.006256ms","start":"2026-08-19T20:29:49.165902Z","end":"2026-08-19T20:29:49.318909Z","steps":["trace[1783218322] 'process raft request' (duration: 152.85477ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:05.65744Z","caller":"traceutil/trace.go:171","msg":"trace[971862585] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"150.908943ms","start":"2026-08-19T20:30:05.506255Z","end":"2026-08-19T20:30:05.657164Z","steps":["trace[971862585] 'process raft request' (duration: 150.340385ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:11.053166Z","caller":"traceutil/trace.go:171","msg":"trace[1239883009] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"112.371028ms","start":"2026-08-19T20:30:10.940775Z","end":"2026-08-19T20:30:11.053146Z","steps":["trace[1239883009] 'process raft request' (duration: 112.080509ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:11.288821Z","caller":"traceutil/trace.go:171","msg":"trace[919279027] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"141.397137ms","start":"2026-08-19T20:30:11.147406Z","end":"2026-08-19T20:30:11.288803Z","steps":["trace[919279027] 'read index received' (duration: 101.014395ms)","trace[919279027] 'applied index is now lower than readState.Index' (duration: 40.382052ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:30:11.288917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.479327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T20:30:11.288918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.50691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:30:11.288925Z","caller":"traceutil/trace.go:171","msg":"trace[294837650] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"200.261366ms","start":"2026-08-19T20:30:11.088625Z","end":"2026-08-19T20:30:11.288887Z","steps":["trace[294837650] 'process raft request' (duration: 159.675498ms)","trace[294837650] 'compare' (duration: 40.424242ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:11.289042Z","caller":"traceutil/trace.go:171","msg":"trace[1698572347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"141.677485ms","start":"2026-08-19T20:30:11.147348Z","end":"2026-08-19T20:30:11.289026Z","steps":["trace[1698572347] 'agreement among raft nodes before linearized reading' (duration: 141.5205ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:11.288937Z","caller":"traceutil/trace.go:171","msg":"trace[1751660036] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1253; }","duration":"123.55016ms","start":"2026-08-19T20:30:11.165379Z","end":"2026-08-19T20:30:11.288929Z","steps":["trace[1751660036] 'agreement among raft nodes before linearized reading' (duration: 123.504368ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:15.201768Z","caller":"traceutil/trace.go:171","msg":"trace[1201734557] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"119.013626ms","start":"2026-08-19T20:30:15.082728Z","end":"2026-08-19T20:30:15.201742Z","steps":["trace[1201734557] 'process raft request' (duration: 118.840191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:15.758557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.902725ms","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-08-19T20:30:15.758644Z","caller":"traceutil/trace.go:171","msg":"trace[1837222765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1267; }","duration":"135.046519ms","start":"2026-08-19T20:30:15.62358Z","end":"2026-08-19T20:30:15.758627Z","steps":["trace[1837222765] 'range keys from in-memory index tree' (duration: 134.697889ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:18.005293Z","caller":"traceutil/trace.go:171","msg":"trace[2034062034] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"133.958513ms","start":"2026-08-19T20:30:17.871311Z","end":"2026-08-19T20:30:18.00527Z","steps":["trace[2034062034] 'process raft request' (duration: 45.296521ms)","trace[2034062034] 'compare' (duration: 88.512608ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:18.181927Z","caller":"traceutil/trace.go:171","msg":"trace[1061313668] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"174.524117ms","start":"2026-08-19T20:30:18.007373Z","end":"2026-08-19T20:30:18.181897Z","steps":["trace[1061313668] 'read index received' (duration: 165.267766ms)","trace[1061313668] 'applied index is now lower than readState.Index' (duration: 9.254861ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:18.181966Z","caller":"traceutil/trace.go:171","msg":"trace[986432026] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"216.949389ms","start":"2026-08-19T20:30:17.964961Z","end":"2026-08-19T20:30:18.18191Z","steps":["trace[986432026] 'process raft request' (duration: 207.772071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:18.182094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.705042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-19T20:30:18.18227Z","caller":"traceutil/trace.go:171","msg":"trace[1522960681] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1277; }","duration":"174.848837ms","start":"2026-08-19T20:30:18.007359Z","end":"2026-08-19T20:30:18.182208Z","steps":["trace[1522960681] 'agreement among raft nodes before linearized reading' (duration: 174.637261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:18.182201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.81499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-08-19T20:30:18.182947Z","caller":"traceutil/trace.go:171","msg":"trace[215362168] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1277; }","duration":"159.603052ms","start":"2026-08-19T20:30:18.023324Z","end":"2026-08-19T20:30:18.182927Z","steps":["trace[215362168] 'agreement among raft nodes before linearized reading' (duration: 158.727066ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:20.038386Z","caller":"traceutil/trace.go:171","msg":"trace[1535694507] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1340; }","duration":"176.564393ms","start":"2026-08-19T20:30:19.861797Z","end":"2026-08-19T20:30:20.038361Z","steps":["trace[1535694507] 'read index received' (duration: 176.45928ms)","trace[1535694507] 'applied index is now lower than readState.Index' (duration: 103.913µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:20.03844Z","caller":"traceutil/trace.go:171","msg":"trace[2030972398] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"188.073031ms","start":"2026-08-19T20:30:19.850323Z","end":"2026-08-19T20:30:20.038396Z","steps":["trace[2030972398] 'process raft request' (duration: 187.864745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:20.038571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.757479ms","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-08-19T20:30:20.038611Z","caller":"traceutil/trace.go:171","msg":"trace[1818757414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1282; }","duration":"176.838101ms","start":"2026-08-19T20:30:19.861763Z","end":"2026-08-19T20:30:20.038601Z","steps":["trace[1818757414] 'agreement among raft nodes before linearized reading' (duration: 176.692397ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:22.230406Z","caller":"traceutil/trace.go:171","msg":"trace[234927165] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"184.247433ms","start":"2026-08-19T20:30:22.046133Z","end":"2026-08-19T20:30:22.23038Z","steps":["trace[234927165] 'read index received' (duration: 184.070638ms)","trace[234927165] 'applied index is now lower than readState.Index' (duration: 175.605µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:22.230533Z","caller":"traceutil/trace.go:171","msg":"trace[715279633] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"184.682496ms","start":"2026-08-19T20:30:22.04584Z","end":"2026-08-19T20:30:22.230522Z","steps":["trace[715279633] 'process raft request' (duration: 184.407738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:22.23076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.617744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:25367"} {"level":"info","ts":"2026-08-19T20:30:22.230798Z","caller":"traceutil/trace.go:171","msg":"trace[685607152] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1288; }","duration":"184.720577ms","start":"2026-08-19T20:30:22.046069Z","end":"2026-08-19T20:30:22.230789Z","steps":["trace[685607152] 'agreement among raft nodes before linearized reading' (duration: 184.585323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:22.231114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.077382ms","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-08-19T20:30:22.231217Z","caller":"traceutil/trace.go:171","msg":"trace[1921844078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"163.214806ms","start":"2026-08-19T20:30:22.067979Z","end":"2026-08-19T20:30:22.231194Z","steps":["trace[1921844078] 'agreement among raft nodes before linearized reading' (duration: 162.957538ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:23.467202Z","caller":"traceutil/trace.go:171","msg":"trace[1095133499] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"124.02993ms","start":"2026-08-19T20:30:23.343142Z","end":"2026-08-19T20:30:23.467172Z","steps":["trace[1095133499] 'process raft request' (duration: 123.858735ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:23.708209Z","caller":"traceutil/trace.go:171","msg":"trace[592273068] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"103.557031ms","start":"2026-08-19T20:30:23.604603Z","end":"2026-08-19T20:30:23.70816Z","steps":["trace[592273068] 'process raft request' (duration: 81.387671ms)","trace[592273068] 'compare' (duration: 21.970144ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:23.848208Z","caller":"traceutil/trace.go:171","msg":"trace[457406461] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"102.317375ms","start":"2026-08-19T20:30:23.745865Z","end":"2026-08-19T20:30:23.848182Z","steps":["trace[457406461] 'read index received' (duration: 23.369426ms)","trace[457406461] 'applied index is now lower than readState.Index' (duration: 78.946439ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:30:23.848466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.521221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:30:23.848392Z","caller":"traceutil/trace.go:171","msg":"trace[1958066699] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"102.588342ms","start":"2026-08-19T20:30:23.745773Z","end":"2026-08-19T20:30:23.848361Z","steps":["trace[1958066699] 'process raft request' (duration: 67.846868ms)","trace[1958066699] 'compare' (duration: 34.404204ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:23.848566Z","caller":"traceutil/trace.go:171","msg":"trace[1071410725] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:1300; }","duration":"102.681886ms","start":"2026-08-19T20:30:23.745824Z","end":"2026-08-19T20:30:23.848506Z","steps":["trace[1071410725] 'agreement among raft nodes before linearized reading' (duration: 102.452999ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:24.025926Z","caller":"traceutil/trace.go:171","msg":"trace[498885400] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"170.84217ms","start":"2026-08-19T20:30:23.855058Z","end":"2026-08-19T20:30:24.0259Z","steps":["trace[498885400] 'process raft request' (duration: 153.558198ms)","trace[498885400] 'compare' (duration: 17.173869ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:24.459138Z","caller":"traceutil/trace.go:171","msg":"trace[743620809] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"122.469389ms","start":"2026-08-19T20:30:24.336639Z","end":"2026-08-19T20:30:24.459108Z","steps":["trace[743620809] 'process raft request' (duration: 51.207066ms)","trace[743620809] 'compare' (duration: 71.135809ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:26.845666Z","caller":"traceutil/trace.go:171","msg":"trace[1635436356] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"137.149962ms","start":"2026-08-19T20:30:26.708494Z","end":"2026-08-19T20:30:26.845644Z","steps":["trace[1635436356] 'process raft request' (duration: 136.971397ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:32.930288Z","caller":"traceutil/trace.go:171","msg":"trace[1467677182] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1414; }","duration":"156.962739ms","start":"2026-08-19T20:30:32.7733Z","end":"2026-08-19T20:30:32.930263Z","steps":["trace[1467677182] 'read index received' (duration: 156.860766ms)","trace[1467677182] 'applied index is now lower than readState.Index' (duration: 101.063µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:30:32.930546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.215486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-19T20:30:32.930588Z","caller":"traceutil/trace.go:171","msg":"trace[1422298286] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1353; }","duration":"157.306309ms","start":"2026-08-19T20:30:32.773272Z","end":"2026-08-19T20:30:32.930579Z","steps":["trace[1422298286] 'agreement among raft nodes before linearized reading' (duration: 157.107713ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:32.930676Z","caller":"traceutil/trace.go:171","msg":"trace[283686425] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"429.173509ms","start":"2026-08-19T20:30:32.501472Z","end":"2026-08-19T20:30:32.930646Z","steps":["trace[283686425] 'process raft request' (duration: 428.637874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:32.930825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:30:32.501461Z","time spent":"429.289962ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:30:40.457798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.162006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:30:40.457885Z","caller":"traceutil/trace.go:171","msg":"trace[1977181691] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1385; }","duration":"315.29565ms","start":"2026-08-19T20:30:40.142566Z","end":"2026-08-19T20:30:40.457862Z","steps":["trace[1977181691] 'range keys from in-memory index tree' (duration: 315.022022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:40.45793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:30:40.142545Z","time spent":"315.376391ms","remote":"127.0.0.1:58764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-08-19T20:30:41.545086Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639527199258619929,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-19T20:30:41.738603Z","caller":"traceutil/trace.go:171","msg":"trace[1321081181] linearizableReadLoop","detail":"{readStateIndex:1451; appliedIndex:1450; }","duration":"693.566522ms","start":"2026-08-19T20:30:41.045014Z","end":"2026-08-19T20:30:41.73858Z","steps":["trace[1321081181] 'read index received' (duration: 693.507901ms)","trace[1321081181] 'applied index is now lower than readState.Index' (duration: 57.311µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:30:41.738759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.722876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T20:30:41.738736Z","caller":"traceutil/trace.go:171","msg":"trace[1148687823] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"759.425504ms","start":"2026-08-19T20:30:40.979268Z","end":"2026-08-19T20:30:41.738693Z","steps":["trace[1148687823] 'process raft request' (duration: 759.125745ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:41.738805Z","caller":"traceutil/trace.go:171","msg":"trace[1917713548] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1387; }","duration":"693.825049ms","start":"2026-08-19T20:30:41.04497Z","end":"2026-08-19T20:30:41.738795Z","steps":["trace[1917713548] 'agreement among raft nodes before linearized reading' (duration: 693.728747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:41.738852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:30:41.044956Z","time spent":"693.883942ms","remote":"127.0.0.1:58896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-08-19T20:30:41.7389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:30:40.979244Z","time spent":"759.574378ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:30:41.759498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.407035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-19T20:30:41.75964Z","caller":"traceutil/trace.go:171","msg":"trace[1545132529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1388; }","duration":"667.590971ms","start":"2026-08-19T20:30:41.092028Z","end":"2026-08-19T20:30:41.759619Z","steps":["trace[1545132529] 'agreement among raft nodes before linearized reading' (duration: 667.318333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:41.759533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.215741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T20:30:41.759715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:30:41.092011Z","time spent":"667.692065ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-19T20:30:41.759788Z","caller":"traceutil/trace.go:171","msg":"trace[315552179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1388; }","duration":"613.489539ms","start":"2026-08-19T20:30:41.14626Z","end":"2026-08-19T20:30:41.75975Z","steps":["trace[315552179] 'agreement among raft nodes before linearized reading' (duration: 613.21315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:41.759892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:30:41.146247Z","time spent":"613.611982ms","remote":"127.0.0.1:58596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T20:30:44.07612Z","caller":"traceutil/trace.go:171","msg":"trace[1976392212] linearizableReadLoop","detail":"{readStateIndex:1464; appliedIndex:1463; }","duration":"282.560262ms","start":"2026-08-19T20:30:43.793505Z","end":"2026-08-19T20:30:44.076065Z","steps":["trace[1976392212] 'read index received' (duration: 4.860843ms)","trace[1976392212] 'applied index is now lower than readState.Index' (duration: 277.698099ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:44.076192Z","caller":"traceutil/trace.go:171","msg":"trace[1748666707] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"311.563113ms","start":"2026-08-19T20:30:43.764606Z","end":"2026-08-19T20:30:44.076169Z","steps":["trace[1748666707] 'process raft request' (duration: 311.183522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:44.076323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:30:43.764592Z","time spent":"311.658686ms","remote":"127.0.0.1:58884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:30:44.076354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.835471ms","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-08-19T20:30:44.07643Z","caller":"traceutil/trace.go:171","msg":"trace[2044485841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1398; }","duration":"282.970704ms","start":"2026-08-19T20:30:43.793444Z","end":"2026-08-19T20:30:44.076414Z","steps":["trace[2044485841] 'agreement among raft nodes before linearized reading' (duration: 282.762048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:44.07645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.989419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-19T20:30:44.076532Z","caller":"traceutil/trace.go:171","msg":"trace[2145448352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1398; }","duration":"277.100032ms","start":"2026-08-19T20:30:43.799419Z","end":"2026-08-19T20:30:44.076519Z","steps":["trace[2145448352] 'agreement among raft nodes before linearized reading' (duration: 276.817744ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:50.289963Z","caller":"traceutil/trace.go:171","msg":"trace[1890714789] linearizableReadLoop","detail":"{readStateIndex:1488; appliedIndex:1487; }","duration":"107.273955ms","start":"2026-08-19T20:30:50.182653Z","end":"2026-08-19T20:30:50.289927Z","steps":["trace[1890714789] 'read index received' (duration: 107.08973ms)","trace[1890714789] 'applied index is now lower than readState.Index' (duration: 183.465µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:50.290027Z","caller":"traceutil/trace.go:171","msg":"trace[632371346] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"111.363524ms","start":"2026-08-19T20:30:50.178604Z","end":"2026-08-19T20:30:50.289967Z","steps":["trace[632371346] 'process raft request' (duration: 111.186439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:50.290203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.532241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-19T20:30:50.290248Z","caller":"traceutil/trace.go:171","msg":"trace[912511099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1421; }","duration":"107.593583ms","start":"2026-08-19T20:30:50.182643Z","end":"2026-08-19T20:30:50.290237Z","steps":["trace[912511099] 'agreement among raft nodes before linearized reading' (duration: 107.421038ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:54.476209Z","caller":"traceutil/trace.go:171","msg":"trace[68588795] linearizableReadLoop","detail":"{readStateIndex:1502; appliedIndex:1501; }","duration":"112.582223ms","start":"2026-08-19T20:30:54.363601Z","end":"2026-08-19T20:30:54.476184Z","steps":["trace[68588795] 'read index received' (duration: 104.366526ms)","trace[68588795] 'applied index is now lower than readState.Index' (duration: 8.214457ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:54.476408Z","caller":"traceutil/trace.go:171","msg":"trace[136444929] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"115.254865ms","start":"2026-08-19T20:30:54.361116Z","end":"2026-08-19T20:30:54.476371Z","steps":["trace[136444929] 'process raft request' (duration: 106.839253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:54.47652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.855191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-08-19T20:30:54.476604Z","caller":"traceutil/trace.go:171","msg":"trace[1470039410] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap; range_end:; response_count:1; response_revision:1434; }","duration":"113.018225ms","start":"2026-08-19T20:30:54.363569Z","end":"2026-08-19T20:30:54.476588Z","steps":["trace[1470039410] 'agreement among raft nodes before linearized reading' (duration: 112.81458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:54.476656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.083205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-08-19T20:30:54.476759Z","caller":"traceutil/trace.go:171","msg":"trace[331849622] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation; range_end:; response_count:1; response_revision:1434; }","duration":"108.24003ms","start":"2026-08-19T20:30:54.368495Z","end":"2026-08-19T20:30:54.476735Z","steps":["trace[331849622] 'agreement among raft nodes before linearized reading' (duration: 108.038115ms)"],"step_count":1}