{"level":"warn","ts":"2026-04-21T18:08:06.132556Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T18:08:06.132628Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.237: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.237:2380","--initial-cluster=instance=https://199.19.213.237:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.237:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.237:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-21T18:08:06.13269Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T18:08:06.132702Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.237:2380"]} {"level":"info","ts":"2026-04-21T18:08:06.132725Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-21T18:08:06.133302Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"]} {"level":"info","ts":"2026-04-21T18:08:06.133379Z","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.237:2380"],"listen-peer-urls":["https://199.19.213.237:2380"],"advertise-client-urls":["https://199.19.213.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.237:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-21T18:08:06.145548Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.962415ms"} {"level":"info","ts":"2026-04-21T18:08:06.164201Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c51d7a9ea5537d6e","cluster-id":"bea7a5ea8f053dc3"} {"level":"info","ts":"2026-04-21T18:08:06.164304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T18:08:06.164332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became follower at term 0"} {"level":"info","ts":"2026-04-21T18:08:06.164345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c51d7a9ea5537d6e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T18:08:06.164361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became follower at term 1"} {"level":"info","ts":"2026-04-21T18:08:06.164398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=(14203643621593546094)"} {"level":"warn","ts":"2026-04-21T18:08:06.187811Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T18:08:06.194792Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T18:08:06.196113Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-21T18:08:06.198606Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c51d7a9ea5537d6e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T18:08:06.199445Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c51d7a9ea5537d6e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T18:08:06.199642Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T18:08:06.204077Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T18:08:06.204149Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T18:08:06.199701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=(14203643621593546094)"} {"level":"info","ts":"2026-04-21T18:08:06.205423Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bea7a5ea8f053dc3","local-member-id":"c51d7a9ea5537d6e","added-peer-id":"c51d7a9ea5537d6e","added-peer-peer-urls":["https://199.19.213.237:2380"]} {"level":"info","ts":"2026-04-21T18:08:06.209735Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-21T18:08:06.209883Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.237:2380"} {"level":"info","ts":"2026-04-21T18:08:06.209953Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.237:2380"} {"level":"info","ts":"2026-04-21T18:08:06.210207Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c51d7a9ea5537d6e","initial-advertise-peer-urls":["https://199.19.213.237:2380"],"listen-peer-urls":["https://199.19.213.237:2380"],"advertise-client-urls":["https://199.19.213.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T18:08:06.210254Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T18:08:06.965552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T18:08:06.965608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T18:08:06.965622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e received MsgPreVoteResp from c51d7a9ea5537d6e at term 1"} {"level":"info","ts":"2026-04-21T18:08:06.965634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became candidate at term 2"} {"level":"info","ts":"2026-04-21T18:08:06.96564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e received MsgVoteResp from c51d7a9ea5537d6e at term 2"} {"level":"info","ts":"2026-04-21T18:08:06.965648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became leader at term 2"} {"level":"info","ts":"2026-04-21T18:08:06.965658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c51d7a9ea5537d6e elected leader c51d7a9ea5537d6e at term 2"} {"level":"info","ts":"2026-04-21T18:08:06.966482Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:08:06.967909Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bea7a5ea8f053dc3","local-member-id":"c51d7a9ea5537d6e","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:08:06.967958Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:08:06.96797Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:08:06.967985Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c51d7a9ea5537d6e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.237:2379]}","request-path":"/0/members/c51d7a9ea5537d6e/attributes","cluster-id":"bea7a5ea8f053dc3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T18:08:06.968297Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T18:08:06.968843Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T18:08:06.969117Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T18:08:06.969168Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T18:08:06.972211Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T18:08:06.972784Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.237:2379"} {"level":"info","ts":"2026-04-21T18:08:08.493406Z","caller":"traceutil/trace.go:171","msg":"trace[848345275] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"148.631199ms","start":"2026-04-21T18:08:08.34475Z","end":"2026-04-21T18:08:08.493382Z","steps":["trace[848345275] 'process raft request' (duration: 148.51152ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.493387Z","caller":"traceutil/trace.go:171","msg":"trace[227891779] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"153.411253ms","start":"2026-04-21T18:08:08.339904Z","end":"2026-04-21T18:08:08.493315Z","steps":["trace[227891779] 'process raft request' (duration: 123.280994ms)","trace[227891779] 'compare' (duration: 29.980768ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:08:08.493662Z","caller":"traceutil/trace.go:171","msg":"trace[1527238440] linearizableReadLoop","detail":"{readStateIndex:11; appliedIndex:10; }","duration":"149.289817ms","start":"2026-04-21T18:08:08.344357Z","end":"2026-04-21T18:08:08.493646Z","steps":["trace[1527238440] 'read index received' (duration: 47.688643ms)","trace[1527238440] 'applied index is now lower than readState.Index' (duration: 101.600364ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:08:08.493788Z","caller":"traceutil/trace.go:171","msg":"trace[1135096852] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"148.883276ms","start":"2026-04-21T18:08:08.344857Z","end":"2026-04-21T18:08:08.49374Z","steps":["trace[1135096852] 'process raft request' (duration: 148.437184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:08.493823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.448408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T18:08:08.49391Z","caller":"traceutil/trace.go:171","msg":"trace[1716537632] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:14; }","duration":"149.554395ms","start":"2026-04-21T18:08:08.344315Z","end":"2026-04-21T18:08:08.493869Z","steps":["trace[1716537632] 'agreement among raft nodes before linearized reading' (duration: 149.422076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:08.493914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.025212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T18:08:08.493963Z","caller":"traceutil/trace.go:171","msg":"trace[2142449906] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:14; }","duration":"141.139469ms","start":"2026-04-21T18:08:08.352809Z","end":"2026-04-21T18:08:08.493949Z","steps":["trace[2142449906] 'agreement among raft nodes before linearized reading' (duration: 141.025221ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.493655Z","caller":"traceutil/trace.go:171","msg":"trace[1418364121] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"147.886476ms","start":"2026-04-21T18:08:08.345748Z","end":"2026-04-21T18:08:08.493634Z","steps":["trace[1418364121] 'process raft request' (duration: 147.703113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:08.494154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.663245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-21T18:08:08.494168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.885353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T18:08:08.49419Z","caller":"traceutil/trace.go:171","msg":"trace[1690581728] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:14; }","duration":"115.708898ms","start":"2026-04-21T18:08:08.378473Z","end":"2026-04-21T18:08:08.494181Z","steps":["trace[1690581728] 'agreement among raft nodes before linearized reading' (duration: 115.659654ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.494203Z","caller":"traceutil/trace.go:171","msg":"trace[1113224786] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:14; }","duration":"131.935436ms","start":"2026-04-21T18:08:08.362256Z","end":"2026-04-21T18:08:08.494192Z","steps":["trace[1113224786] 'agreement among raft nodes before linearized reading' (duration: 131.889993ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.494291Z","caller":"traceutil/trace.go:171","msg":"trace[404158942] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"149.398394ms","start":"2026-04-21T18:08:08.34488Z","end":"2026-04-21T18:08:08.494278Z","steps":["trace[404158942] 'process raft request' (duration: 148.488789ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.494419Z","caller":"traceutil/trace.go:171","msg":"trace[2005663927] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"149.246272ms","start":"2026-04-21T18:08:08.345153Z","end":"2026-04-21T18:08:08.4944Z","steps":["trace[2005663927] 'process raft request' (duration: 148.23805ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.697001Z","caller":"traceutil/trace.go:171","msg":"trace[60800933] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"198.584015ms","start":"2026-04-21T18:08:08.498384Z","end":"2026-04-21T18:08:08.696968Z","steps":["trace[60800933] 'process raft request' (duration: 149.919632ms)","trace[60800933] 'compare' (duration: 48.498421ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:08:08.697117Z","caller":"traceutil/trace.go:171","msg":"trace[78413011] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"198.543532ms","start":"2026-04-21T18:08:08.498547Z","end":"2026-04-21T18:08:08.697091Z","steps":["trace[78413011] 'process raft request' (duration: 198.394141ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.697282Z","caller":"traceutil/trace.go:171","msg":"trace[179125913] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"198.862225ms","start":"2026-04-21T18:08:08.498412Z","end":"2026-04-21T18:08:08.697274Z","steps":["trace[179125913] 'process raft request' (duration: 198.468097ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.697273Z","caller":"traceutil/trace.go:171","msg":"trace[1919129808] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"198.416312ms","start":"2026-04-21T18:08:08.498827Z","end":"2026-04-21T18:08:08.697243Z","steps":["trace[1919129808] 'process raft request' (duration: 198.176584ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.697466Z","caller":"traceutil/trace.go:171","msg":"trace[996367077] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"198.483507ms","start":"2026-04-21T18:08:08.498973Z","end":"2026-04-21T18:08:08.697457Z","steps":["trace[996367077] 'process raft request' (duration: 198.277062ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.697477Z","caller":"traceutil/trace.go:171","msg":"trace[2035540823] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"198.778618ms","start":"2026-04-21T18:08:08.498682Z","end":"2026-04-21T18:08:08.69746Z","steps":["trace[2035540823] 'process raft request' (duration: 198.292283ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.697545Z","caller":"traceutil/trace.go:171","msg":"trace[1185174724] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"198.575544ms","start":"2026-04-21T18:08:08.498958Z","end":"2026-04-21T18:08:08.697534Z","steps":["trace[1185174724] 'process raft request' (duration: 198.069097ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.697656Z","caller":"traceutil/trace.go:171","msg":"trace[898192244] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"143.321618ms","start":"2026-04-21T18:08:08.554315Z","end":"2026-04-21T18:08:08.697637Z","steps":["trace[898192244] 'process raft request' (duration: 143.141984ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.697715Z","caller":"traceutil/trace.go:171","msg":"trace[780788015] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"192.945359ms","start":"2026-04-21T18:08:08.504699Z","end":"2026-04-21T18:08:08.697645Z","steps":["trace[780788015] 'process raft request' (duration: 192.669918ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:08.697684Z","caller":"traceutil/trace.go:171","msg":"trace[1524846812] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:17; }","duration":"198.835402ms","start":"2026-04-21T18:08:08.498796Z","end":"2026-04-21T18:08:08.697631Z","steps":["trace[1524846812] 'read index received' (duration: 16.139801ms)","trace[1524846812] 'applied index is now lower than readState.Index' (duration: 182.691341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:08.697874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.055919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-04-21T18:08:08.697952Z","caller":"traceutil/trace.go:171","msg":"trace[405866944] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23; }","duration":"199.147965ms","start":"2026-04-21T18:08:08.498773Z","end":"2026-04-21T18:08:08.697921Z","steps":["trace[405866944] 'agreement among raft nodes before linearized reading' (duration: 198.963382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:08.698138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.589451ms","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-04-21T18:08:08.69827Z","caller":"traceutil/trace.go:171","msg":"trace[1042055936] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:23; }","duration":"191.742472ms","start":"2026-04-21T18:08:08.506486Z","end":"2026-04-21T18:08:08.698228Z","steps":["trace[1042055936] 'agreement among raft nodes before linearized reading' (duration: 191.541647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:08.698145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.799092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T18:08:08.699195Z","caller":"traceutil/trace.go:171","msg":"trace[1097497671] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:23; }","duration":"180.872569ms","start":"2026-04-21T18:08:08.518304Z","end":"2026-04-21T18:08:08.699176Z","steps":["trace[1097497671] 'agreement among raft nodes before linearized reading' (duration: 179.730097ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:17.632414Z","caller":"traceutil/trace.go:171","msg":"trace[1569066145] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"180.264884ms","start":"2026-04-21T18:08:17.452125Z","end":"2026-04-21T18:08:17.63239Z","steps":["trace[1569066145] 'process raft request' (duration: 180.134327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:17.855499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.464339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:08:17.855609Z","caller":"traceutil/trace.go:171","msg":"trace[2106413782] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"215.781137ms","start":"2026-04-21T18:08:17.63981Z","end":"2026-04-21T18:08:17.855591Z","steps":["trace[2106413782] 'process raft request' (duration: 68.367538ms)","trace[2106413782] 'compare' (duration: 146.319012ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:08:18.671039Z","caller":"traceutil/trace.go:171","msg":"trace[1811074886] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"214.490032ms","start":"2026-04-21T18:08:18.456519Z","end":"2026-04-21T18:08:18.671009Z","steps":["trace[1811074886] 'process raft request' (duration: 214.330734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:23.034799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.767838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:08:23.034895Z","caller":"traceutil/trace.go:171","msg":"trace[2013429833] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"259.684628ms","start":"2026-04-21T18:08:22.775195Z","end":"2026-04-21T18:08:23.034879Z","steps":["trace[2013429833] 'read index received' (duration: 128.767533ms)","trace[2013429833] 'applied index is now lower than readState.Index' (duration: 130.916115ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:08:23.034995Z","caller":"traceutil/trace.go:171","msg":"trace[13037027] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"262.323941ms","start":"2026-04-21T18:08:22.77266Z","end":"2026-04-21T18:08:23.034984Z","steps":["trace[13037027] 'process raft request' (duration: 131.280992ms)","trace[13037027] 'compare' (duration: 130.683634ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:23.035227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.024391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-04-21T18:08:23.035295Z","caller":"traceutil/trace.go:171","msg":"trace[82724500] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:371; }","duration":"260.119695ms","start":"2026-04-21T18:08:22.77516Z","end":"2026-04-21T18:08:23.035279Z","steps":["trace[82724500] 'agreement among raft nodes before linearized reading' (duration: 260.024351ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:23.481168Z","caller":"traceutil/trace.go:171","msg":"trace[2133870590] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"440.430192ms","start":"2026-04-21T18:08:23.040708Z","end":"2026-04-21T18:08:23.481138Z","steps":["trace[2133870590] 'process raft request' (duration: 426.215154ms)","trace[2133870590] 'compare' (duration: 13.741579ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:23.481328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:23.040674Z","time spent":"440.580789ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:08:23.481632Z","caller":"traceutil/trace.go:171","msg":"trace[1232206626] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"433.265945ms","start":"2026-04-21T18:08:23.048337Z","end":"2026-04-21T18:08:23.481603Z","steps":["trace[1232206626] 'process raft request' (duration: 432.676421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:23.481791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:23.04832Z","time spent":"433.38081ms","remote":"127.0.0.1:46018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:08:24.01144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.430341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:08:24.011625Z","caller":"traceutil/trace.go:171","msg":"trace[987148517] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"229.825461ms","start":"2026-04-21T18:08:23.78177Z","end":"2026-04-21T18:08:24.011595Z","steps":["trace[987148517] 'process raft request' (duration: 107.172751ms)","trace[987148517] 'compare' (duration: 122.302086ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:08:24.011698Z","caller":"traceutil/trace.go:171","msg":"trace[1375874541] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"226.88702ms","start":"2026-04-21T18:08:23.784798Z","end":"2026-04-21T18:08:24.011685Z","steps":["trace[1375874541] 'process raft request' (duration: 226.768915ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:24.011682Z","caller":"traceutil/trace.go:171","msg":"trace[1846867696] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"229.579901ms","start":"2026-04-21T18:08:23.782053Z","end":"2026-04-21T18:08:24.011633Z","steps":["trace[1846867696] 'process raft request' (duration: 229.466106ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:24.011831Z","caller":"traceutil/trace.go:171","msg":"trace[1296083597] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:411; }","duration":"228.586749ms","start":"2026-04-21T18:08:23.783232Z","end":"2026-04-21T18:08:24.011818Z","steps":["trace[1296083597] 'read index received' (duration: 28.230627ms)","trace[1296083597] 'applied index is now lower than readState.Index' (duration: 200.354812ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:24.01194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.699053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-04-21T18:08:24.011961Z","caller":"traceutil/trace.go:171","msg":"trace[1013144380] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:399; }","duration":"228.754076ms","start":"2026-04-21T18:08:23.783199Z","end":"2026-04-21T18:08:24.011953Z","steps":["trace[1013144380] 'agreement among raft nodes before linearized reading' (duration: 228.679804ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:24.011962Z","caller":"traceutil/trace.go:171","msg":"trace[2136115783] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"225.774093ms","start":"2026-04-21T18:08:23.786171Z","end":"2026-04-21T18:08:24.011945Z","steps":["trace[2136115783] 'process raft request' (duration: 225.424149ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:24.167989Z","caller":"traceutil/trace.go:171","msg":"trace[275394430] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"144.937885ms","start":"2026-04-21T18:08:24.02303Z","end":"2026-04-21T18:08:24.167968Z","steps":["trace[275394430] 'process raft request' (duration: 128.586535ms)","trace[275394430] 'compare' (duration: 16.232996ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:08:30.276029Z","caller":"traceutil/trace.go:171","msg":"trace[370956500] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"343.511549ms","start":"2026-04-21T18:08:29.932502Z","end":"2026-04-21T18:08:30.276013Z","steps":["trace[370956500] 'read index received' (duration: 343.358795ms)","trace[370956500] 'applied index is now lower than readState.Index' (duration: 152.074µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:30.276134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.605902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.237\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T18:08:30.276152Z","caller":"traceutil/trace.go:171","msg":"trace[2032422268] range","detail":"{range_begin:/registry/masterleases/199.19.213.237; range_end:; response_count:1; response_revision:418; }","duration":"343.650473ms","start":"2026-04-21T18:08:29.932496Z","end":"2026-04-21T18:08:30.276147Z","steps":["trace[2032422268] 'agreement among raft nodes before linearized reading' (duration: 343.586471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:30.276177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:29.932453Z","time spent":"343.718006ms","remote":"127.0.0.1:45594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.237\" "} {"level":"info","ts":"2026-04-21T18:08:30.276191Z","caller":"traceutil/trace.go:171","msg":"trace[1963413239] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"733.625641ms","start":"2026-04-21T18:08:29.542545Z","end":"2026-04-21T18:08:30.276171Z","steps":["trace[1963413239] 'process raft request' (duration: 733.363203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:30.276293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:29.542534Z","time spent":"733.699484ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:08:35.464477Z","caller":"traceutil/trace.go:171","msg":"trace[1499787599] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"307.083922ms","start":"2026-04-21T18:08:35.157367Z","end":"2026-04-21T18:08:35.464451Z","steps":["trace[1499787599] 'process raft request' (duration: 306.896837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:35.464649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:35.157347Z","time spent":"307.219876ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:08:39.444297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038334886693312553,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:08:39.945464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038334886693312553,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:08:40.445833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038334886693312553,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:08:40.466179Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.529852727s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-21T18:08:40.609623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.09398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:08:40.609775Z","caller":"traceutil/trace.go:171","msg":"trace[1710087185] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"1.673527399s","start":"2026-04-21T18:08:38.936219Z","end":"2026-04-21T18:08:40.609746Z","steps":["trace[1710087185] 'process raft request' (duration: 1.530205755s)","trace[1710087185] 'compare' (duration: 142.887116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:40.609845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:38.936198Z","time spent":"1.6736178s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:08:40.941378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997359371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-21T18:08:40.941512Z","caller":"traceutil/trace.go:171","msg":"trace[1709693327] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.997542324s","start":"2026-04-21T18:08:38.943948Z","end":"2026-04-21T18:08:40.94149Z","steps":["trace[1709693327] 'agreement among raft nodes before linearized reading' (duration: 1.997377041s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:40.941569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:38.94394Z","time spent":"1.997617996s","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/21 18:08:40 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-21T18:08:42.2681Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.800515685s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T18:08:42.268569Z","caller":"traceutil/trace.go:171","msg":"trace[943854583] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"3.324250651s","start":"2026-04-21T18:08:38.944284Z","end":"2026-04-21T18:08:42.268535Z","steps":["trace[943854583] 'process raft request' (duration: 3.324042457s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:42.268709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:38.944263Z","time spent":"3.324375344s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:08:42.268839Z","caller":"traceutil/trace.go:171","msg":"trace[1487883477] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"3.310320648s","start":"2026-04-21T18:08:38.958481Z","end":"2026-04-21T18:08:42.268802Z","steps":["trace[1487883477] 'process raft request' (duration: 3.310035253s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:42.26892Z","caller":"traceutil/trace.go:171","msg":"trace[1495571691] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"3.316885666s","start":"2026-04-21T18:08:38.951992Z","end":"2026-04-21T18:08:42.268877Z","steps":["trace[1495571691] 'process raft request' (duration: 3.316473948s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:42.268989Z","caller":"traceutil/trace.go:171","msg":"trace[1922260728] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"3.303953086s","start":"2026-04-21T18:08:38.965027Z","end":"2026-04-21T18:08:42.26898Z","steps":["trace[1922260728] 'process raft request' (duration: 3.303605339s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:42.269012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:38.958469Z","time spent":"3.310462192s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:08:42.269058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:38.964988Z","time spent":"3.304032037s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:08:42.269133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:38.951977Z","time spent":"3.317014478s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:08:42.269142Z","caller":"traceutil/trace.go:171","msg":"trace[1316994563] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"3.301678405s","start":"2026-04-21T18:08:38.967444Z","end":"2026-04-21T18:08:42.269122Z","steps":["trace[1316994563] 'process raft request' (duration: 3.301344419s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:42.269156Z","caller":"traceutil/trace.go:171","msg":"trace[1385099074] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"3.301962532s","start":"2026-04-21T18:08:38.96718Z","end":"2026-04-21T18:08:42.269143Z","steps":["trace[1385099074] 'process raft request' (duration: 3.301567985s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:42.269213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:38.967165Z","time spent":"3.302024982s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:08:42.268945Z","caller":"traceutil/trace.go:171","msg":"trace[1185723818] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"3.303799512s","start":"2026-04-21T18:08:38.965106Z","end":"2026-04-21T18:08:42.268906Z","steps":["trace[1185723818] 'process raft request' (duration: 3.303599669s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:42.269218Z","caller":"traceutil/trace.go:171","msg":"trace[683795186] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:490; }","duration":"3.32521968s","start":"2026-04-21T18:08:38.943976Z","end":"2026-04-21T18:08:42.269196Z","steps":["trace[683795186] 'read index received' (duration: 1.522532493s)","trace[683795186] 'applied index is now lower than readState.Index' (duration: 1.802686157s)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:42.269279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:38.965079Z","time spent":"3.304174951s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:08:42.2693Z","caller":"traceutil/trace.go:171","msg":"trace[941917018] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"3.268883413s","start":"2026-04-21T18:08:39.000408Z","end":"2026-04-21T18:08:42.269291Z","steps":["trace[941917018] 'process raft request' (duration: 3.268597407s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:42.269335Z","caller":"traceutil/trace.go:171","msg":"trace[469313500] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"2.713235908s","start":"2026-04-21T18:08:39.556083Z","end":"2026-04-21T18:08:42.269319Z","steps":["trace[469313500] 'process raft request' (duration: 2.713052063s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:42.269345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:39.000373Z","time spent":"3.268946814s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:08:42.269242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:38.967433Z","time spent":"3.301753996s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:08:42.269441Z","caller":"traceutil/trace.go:171","msg":"trace[784449698] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"3.293732338s","start":"2026-04-21T18:08:38.975694Z","end":"2026-04-21T18:08:42.269426Z","steps":["trace[784449698] 'process raft request' (duration: 3.293239238s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:42.269445Z","caller":"traceutil/trace.go:171","msg":"trace[859836369] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"3.151991435s","start":"2026-04-21T18:08:39.117442Z","end":"2026-04-21T18:08:42.269433Z","steps":["trace[859836369] 'process raft request' (duration: 3.151630427s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:42.269449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:39.55606Z","time spent":"2.713309789s","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:08:42.269473Z","caller":"traceutil/trace.go:171","msg":"trace[744869092] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"3.296813945s","start":"2026-04-21T18:08:38.972649Z","end":"2026-04-21T18:08:42.269463Z","steps":["trace[744869092] 'process raft request' (duration: 3.296179083s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:42.269494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:38.975687Z","time spent":"3.293786379s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:08:42.269501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:39.117409Z","time spent":"3.152069746s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:08:42.26954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:38.972639Z","time spent":"3.296875706s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:08:42.941536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999561423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-21T18:08:42.941633Z","caller":"traceutil/trace.go:171","msg":"trace[896917469] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999750458s","start":"2026-04-21T18:08:40.941853Z","end":"2026-04-21T18:08:42.941603Z","steps":["trace[896917469] 'agreement among raft nodes before linearized reading' (duration: 1.999643896s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:42.941682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:40.941826Z","time spent":"1.99984677s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/21 18:08:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-21T18:08:43.337862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.788959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:08:43.338126Z","caller":"traceutil/trace.go:171","msg":"trace[21464510] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:503; }","duration":"1.068824365s","start":"2026-04-21T18:08:42.269286Z","end":"2026-04-21T18:08:43.33811Z","steps":["trace[21464510] 'read index received' (duration: 331.761991ms)","trace[21464510] 'applied index is now lower than readState.Index' (duration: 737.061614ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:08:43.338115Z","caller":"traceutil/trace.go:171","msg":"trace[1550236595] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"2.72046795s","start":"2026-04-21T18:08:40.617598Z","end":"2026-04-21T18:08:43.338066Z","steps":["trace[1550236595] 'process raft request' (duration: 1.983417046s)","trace[1550236595] 'compare' (duration: 736.672367ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:43.338345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.426070923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T18:08:43.338488Z","caller":"traceutil/trace.go:171","msg":"trace[469762003] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.160067321s","start":"2026-04-21T18:08:42.17841Z","end":"2026-04-21T18:08:43.338477Z","steps":["trace[469762003] 'process raft request' (duration: 1.159637904s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:43.338461Z","caller":"traceutil/trace.go:171","msg":"trace[1111478426] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"2.720247734s","start":"2026-04-21T18:08:40.618197Z","end":"2026-04-21T18:08:43.338445Z","steps":["trace[1111478426] 'process raft request' (duration: 2.719764416s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:43.338504Z","caller":"traceutil/trace.go:171","msg":"trace[1587886097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:487; }","duration":"2.426281797s","start":"2026-04-21T18:08:40.912196Z","end":"2026-04-21T18:08:43.338478Z","steps":["trace[1587886097] 'agreement among raft nodes before linearized reading' (duration: 2.426051943s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.338504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.856897658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-21T18:08:43.338558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:42.178384Z","time spent":"1.160135933s","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:08:43.338578Z","caller":"traceutil/trace.go:171","msg":"trace[286274275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:487; }","duration":"3.857004581s","start":"2026-04-21T18:08:39.48156Z","end":"2026-04-21T18:08:43.338565Z","steps":["trace[286274275] 'agreement among raft nodes before linearized reading' (duration: 3.85691194s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.338614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:39.48154Z","time spent":"3.857065482s","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T18:08:43.338608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:40.618183Z","time spent":"2.720353707s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:08:43.338718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:40.912169Z","time spent":"2.426534131s","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T18:08:43.338402Z","caller":"traceutil/trace.go:171","msg":"trace[2135410065] transaction","detail":"{read_only:false; number_of_response:1; response_revision:486; }","duration":"2.719525319s","start":"2026-04-21T18:08:40.618854Z","end":"2026-04-21T18:08:43.338379Z","steps":["trace[2135410065] 'process raft request' (duration: 2.719167153s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.339006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:40.618826Z","time spent":"2.720086979s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6938,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:08:43.338435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:40.617574Z","time spent":"2.720799936s","remote":"127.0.0.1:46104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:08:43.33834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.975779148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T18:08:43.339509Z","caller":"traceutil/trace.go:171","msg":"trace[861023480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"2.97698248s","start":"2026-04-21T18:08:40.362512Z","end":"2026-04-21T18:08:43.339495Z","steps":["trace[861023480] 'agreement among raft nodes before linearized reading' (duration: 2.975787449s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.339703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:40.36249Z","time spent":"2.977149403s","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T18:08:43.339846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.222890427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-21T18:08:43.338436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.405137715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.237\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T18:08:43.339966Z","caller":"traceutil/trace.go:171","msg":"trace[1537025931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:487; }","duration":"4.22304656s","start":"2026-04-21T18:08:39.116884Z","end":"2026-04-21T18:08:43.33993Z","steps":["trace[1537025931] 'agreement among raft nodes before linearized reading' (duration: 4.221441871s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.340036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:39.116869Z","time spent":"4.223152191s","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T18:08:43.340058Z","caller":"traceutil/trace.go:171","msg":"trace[1163465671] range","detail":"{range_begin:/registry/masterleases/199.19.213.237; range_end:; response_count:1; response_revision:487; }","duration":"3.406746514s","start":"2026-04-21T18:08:39.933255Z","end":"2026-04-21T18:08:43.340002Z","steps":["trace[1163465671] 'agreement among raft nodes before linearized reading' (duration: 3.405154856s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.340179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:39.933234Z","time spent":"3.406899176s","remote":"127.0.0.1:45594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.237\" "} {"level":"info","ts":"2026-04-21T18:08:43.468407Z","caller":"traceutil/trace.go:171","msg":"trace[1783261268] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.190554587s","start":"2026-04-21T18:08:42.277819Z","end":"2026-04-21T18:08:43.468373Z","steps":["trace[1783261268] 'process raft request' (duration: 1.182750978s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:43.468481Z","caller":"traceutil/trace.go:171","msg":"trace[424614935] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.190066788s","start":"2026-04-21T18:08:42.278395Z","end":"2026-04-21T18:08:43.468462Z","steps":["trace[424614935] 'process raft request' (duration: 1.189736322s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.468568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:42.278388Z","time spent":"1.19013429s","remote":"127.0.0.1:46104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:08:43.468568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:42.277803Z","time spent":"1.190700319s","remote":"127.0.0.1:46104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:08:43.469011Z","caller":"traceutil/trace.go:171","msg":"trace[1444841308] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.187091332s","start":"2026-04-21T18:08:42.281904Z","end":"2026-04-21T18:08:43.468995Z","steps":["trace[1444841308] 'process raft request' (duration: 1.186891968s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:43.469148Z","caller":"traceutil/trace.go:171","msg":"trace[533830430] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.182669539s","start":"2026-04-21T18:08:42.286458Z","end":"2026-04-21T18:08:43.469127Z","steps":["trace[533830430] 'process raft request' (duration: 1.182425355s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.469278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:42.286443Z","time spent":"1.182780183s","remote":"127.0.0.1:46104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:08:43.469304Z","caller":"traceutil/trace.go:171","msg":"trace[1083934724] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.182714261s","start":"2026-04-21T18:08:42.286576Z","end":"2026-04-21T18:08:43.46929Z","steps":["trace[1083934724] 'process raft request' (duration: 1.182351204s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.469377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:42.286567Z","time spent":"1.182777742s","remote":"127.0.0.1:46104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:08:43.469118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:42.281885Z","time spent":"1.187174743s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:08:43.470407Z","caller":"traceutil/trace.go:171","msg":"trace[428765816] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.181729541s","start":"2026-04-21T18:08:42.288661Z","end":"2026-04-21T18:08:43.470391Z","steps":["trace[428765816] 'process raft request' (duration: 1.180320016s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.470495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:42.288627Z","time spent":"1.181828203s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:08:43.471315Z","caller":"traceutil/trace.go:171","msg":"trace[1837768372] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:507; }","duration":"133.133062ms","start":"2026-04-21T18:08:43.338167Z","end":"2026-04-21T18:08:43.4713Z","steps":["trace[1837768372] 'read index received' (duration: 122.412811ms)","trace[1837768372] 'applied index is now lower than readState.Index' (duration: 10.719501ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:43.471546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.187992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:08:43.471588Z","caller":"traceutil/trace.go:171","msg":"trace[1831006681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"522.314153ms","start":"2026-04-21T18:08:42.949264Z","end":"2026-04-21T18:08:43.471578Z","steps":["trace[1831006681] 'agreement among raft nodes before linearized reading' (duration: 522.09278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.471622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:42.949243Z","time spent":"522.371465ms","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T18:08:43.478635Z","caller":"traceutil/trace.go:171","msg":"trace[60068087] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"533.041636ms","start":"2026-04-21T18:08:42.945577Z","end":"2026-04-21T18:08:43.478619Z","steps":["trace[60068087] 'process raft request' (duration: 525.34944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.478707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:42.945552Z","time spent":"533.119428ms","remote":"127.0.0.1:45650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:08:43.47885Z","caller":"traceutil/trace.go:171","msg":"trace[2099802262] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"135.464653ms","start":"2026-04-21T18:08:43.343352Z","end":"2026-04-21T18:08:43.478816Z","steps":["trace[2099802262] 'process raft request' (duration: 127.697005ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:43.478935Z","caller":"traceutil/trace.go:171","msg":"trace[469449699] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.189474658s","start":"2026-04-21T18:08:42.289445Z","end":"2026-04-21T18:08:43.478919Z","steps":["trace[469449699] 'process raft request' (duration: 1.180884295s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:42.289435Z","time spent":"1.189529719s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:08:43.479113Z","caller":"traceutil/trace.go:171","msg":"trace[356086465] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.189464088s","start":"2026-04-21T18:08:42.289637Z","end":"2026-04-21T18:08:43.479101Z","steps":["trace[356086465] 'process raft request' (duration: 1.181200491s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.479156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:42.28963Z","time spent":"1.189501469s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:08:43.479281Z","caller":"traceutil/trace.go:171","msg":"trace[2089948712] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.151679146s","start":"2026-04-21T18:08:42.32759Z","end":"2026-04-21T18:08:43.479269Z","steps":["trace[2089948712] 'process raft request' (duration: 1.143302727s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:43.47932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:42.327567Z","time spent":"1.151736356s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:08:43.478716Z","caller":"traceutil/trace.go:171","msg":"trace[1653111327] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"133.040081ms","start":"2026-04-21T18:08:43.345605Z","end":"2026-04-21T18:08:43.478645Z","steps":["trace[1653111327] 'process raft request' (duration: 125.516188ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:43.479451Z","caller":"traceutil/trace.go:171","msg":"trace[619246273] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"135.120238ms","start":"2026-04-21T18:08:43.344317Z","end":"2026-04-21T18:08:43.479437Z","steps":["trace[619246273] 'process raft request' (duration: 126.77409ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:43.479628Z","caller":"traceutil/trace.go:171","msg":"trace[1528715788] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"132.398639ms","start":"2026-04-21T18:08:43.347206Z","end":"2026-04-21T18:08:43.479605Z","steps":["trace[1528715788] 'process raft request' (duration: 123.98085ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:43.479878Z","caller":"traceutil/trace.go:171","msg":"trace[1171926177] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"134.192182ms","start":"2026-04-21T18:08:43.345668Z","end":"2026-04-21T18:08:43.47986Z","steps":["trace[1171926177] 'process raft request' (duration: 125.485947ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:43.480006Z","caller":"traceutil/trace.go:171","msg":"trace[601388434] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"121.459235ms","start":"2026-04-21T18:08:43.358531Z","end":"2026-04-21T18:08:43.47999Z","steps":["trace[601388434] 'process raft request' (duration: 112.70933ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:50.056027Z","caller":"traceutil/trace.go:171","msg":"trace[1673010898] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"104.319244ms","start":"2026-04-21T18:08:49.951682Z","end":"2026-04-21T18:08:50.056002Z","steps":["trace[1673010898] 'process raft request' (duration: 92.627033ms)","trace[1673010898] 'compare' (duration: 11.584926ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:50.776495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.562661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:08:50.776703Z","caller":"traceutil/trace.go:171","msg":"trace[424992039] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"718.791771ms","start":"2026-04-21T18:08:50.057849Z","end":"2026-04-21T18:08:50.77664Z","steps":["trace[424992039] 'read index received' (duration: 211.272604ms)","trace[424992039] 'applied index is now lower than readState.Index' (duration: 507.515987ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:08:50.780516Z","caller":"traceutil/trace.go:171","msg":"trace[1549133268] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"817.502031ms","start":"2026-04-21T18:08:49.962981Z","end":"2026-04-21T18:08:50.780483Z","steps":["trace[1549133268] 'process raft request' (duration: 306.821618ms)","trace[1549133268] 'compare' (duration: 504.3543ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:50.780669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:49.962945Z","time spent":"817.644945ms","remote":"127.0.0.1:45674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:08:50.779888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.008984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-04-21T18:08:50.780734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.375864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4psf6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-21T18:08:50.780749Z","caller":"traceutil/trace.go:171","msg":"trace[1671748516] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:587; }","duration":"722.905073ms","start":"2026-04-21T18:08:50.05782Z","end":"2026-04-21T18:08:50.780725Z","steps":["trace[1671748516] 'agreement among raft nodes before linearized reading' (duration: 721.901559ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:50.780786Z","caller":"traceutil/trace.go:171","msg":"trace[1957592443] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4psf6; range_end:; response_count:1; response_revision:587; }","duration":"249.431171ms","start":"2026-04-21T18:08:50.531339Z","end":"2026-04-21T18:08:50.780771Z","steps":["trace[1957592443] 'agreement among raft nodes before linearized reading' (duration: 248.482514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:50.780809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:50.057807Z","time spent":"722.982685ms","remote":"127.0.0.1:45730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-21T18:08:51.820843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.111453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:08:51.820967Z","caller":"traceutil/trace.go:171","msg":"trace[958997829] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"882.754856ms","start":"2026-04-21T18:08:50.938188Z","end":"2026-04-21T18:08:51.820943Z","steps":["trace[958997829] 'read index received' (duration: 61.416µs)","trace[958997829] 'applied index is now lower than readState.Index' (duration: 882.691831ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:51.821428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.236014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:08:51.821415Z","caller":"traceutil/trace.go:171","msg":"trace[1217623569] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"961.502641ms","start":"2026-04-21T18:08:50.859876Z","end":"2026-04-21T18:08:51.821379Z","steps":["trace[1217623569] 'process raft request' (duration: 56.752876ms)","trace[1217623569] 'compare' (duration: 903.716484ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:51.821449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.338087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-21T18:08:51.821457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.418115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T18:08:51.821488Z","caller":"traceutil/trace.go:171","msg":"trace[350752183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"883.326513ms","start":"2026-04-21T18:08:50.938145Z","end":"2026-04-21T18:08:51.821472Z","steps":["trace[350752183] 'agreement among raft nodes before linearized reading' (duration: 883.242033ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:51.821511Z","caller":"traceutil/trace.go:171","msg":"trace[1906727426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"284.402602ms","start":"2026-04-21T18:08:51.537091Z","end":"2026-04-21T18:08:51.821493Z","steps":["trace[1906727426] 'agreement among raft nodes before linearized reading' (duration: 284.146652ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:51.821514Z","caller":"traceutil/trace.go:171","msg":"trace[1500459243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"271.494457ms","start":"2026-04-21T18:08:51.550005Z","end":"2026-04-21T18:08:51.821499Z","steps":["trace[1500459243] 'agreement among raft nodes before linearized reading' (duration: 271.35379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:51.821534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:50.938098Z","time spent":"883.42772ms","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T18:08:51.821548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:50.859851Z","time spent":"961.638039ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:08:51.821611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.052856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:08:51.821701Z","caller":"traceutil/trace.go:171","msg":"trace[1748658645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"273.169718ms","start":"2026-04-21T18:08:51.54851Z","end":"2026-04-21T18:08:51.82168Z","steps":["trace[1748658645] 'agreement among raft nodes before linearized reading' (duration: 272.920776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:51.821718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.541844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T18:08:51.821763Z","caller":"traceutil/trace.go:171","msg":"trace[2129641773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"277.624425ms","start":"2026-04-21T18:08:51.544125Z","end":"2026-04-21T18:08:51.821749Z","steps":["trace[2129641773] 'agreement among raft nodes before linearized reading' (duration: 277.504962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:52.708981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.499969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:08:52.709152Z","caller":"traceutil/trace.go:171","msg":"trace[1414502006] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"882.196824ms","start":"2026-04-21T18:08:51.826937Z","end":"2026-04-21T18:08:52.709134Z","steps":["trace[1414502006] 'process raft request' (duration: 148.449872ms)","trace[1414502006] 'compare' (duration: 733.397572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:08:52.709216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:51.826917Z","time spent":"882.270097ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:08:52.709767Z","caller":"traceutil/trace.go:171","msg":"trace[1611896138] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:612; }","duration":"881.867562ms","start":"2026-04-21T18:08:51.827821Z","end":"2026-04-21T18:08:52.709689Z","steps":["trace[1611896138] 'read index received' (duration: 147.534173ms)","trace[1611896138] 'applied index is now lower than readState.Index' (duration: 734.332269ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:08:52.70988Z","caller":"traceutil/trace.go:171","msg":"trace[275990960] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"882.56849ms","start":"2026-04-21T18:08:51.827297Z","end":"2026-04-21T18:08:52.709866Z","steps":["trace[275990960] 'process raft request' (duration: 881.797446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:52.709972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:51.827196Z","time spent":"882.737312ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:08:52.709984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.149567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-21T18:08:52.710024Z","caller":"traceutil/trace.go:171","msg":"trace[1317140527] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:593; }","duration":"882.260942ms","start":"2026-04-21T18:08:51.827751Z","end":"2026-04-21T18:08:52.710012Z","steps":["trace[1317140527] 'agreement among raft nodes before linearized reading' (duration: 882.096209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:52.710075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:51.82774Z","time spent":"882.316458ms","remote":"127.0.0.1:45756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-04-21T18:08:52.709774Z","caller":"traceutil/trace.go:171","msg":"trace[2015724659] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"882.216251ms","start":"2026-04-21T18:08:51.82752Z","end":"2026-04-21T18:08:52.709736Z","steps":["trace[2015724659] 'process raft request' (duration: 882.069205ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:52.710286Z","caller":"traceutil/trace.go:171","msg":"trace[2082140665] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"882.728624ms","start":"2026-04-21T18:08:51.827539Z","end":"2026-04-21T18:08:52.710268Z","steps":["trace[2082140665] 'process raft request' (duration: 882.109066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:52.710538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:51.827508Z","time spent":"882.895175ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:08:52.710637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:51.827523Z","time spent":"883.069685ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:08:52.716314Z","caller":"traceutil/trace.go:171","msg":"trace[1028911118] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"323.790877ms","start":"2026-04-21T18:08:52.392506Z","end":"2026-04-21T18:08:52.716297Z","steps":["trace[1028911118] 'process raft request' (duration: 323.648629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:52.716403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:52.39248Z","time spent":"323.880807ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:08:53.763921Z","caller":"traceutil/trace.go:171","msg":"trace[838614067] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"210.825856ms","start":"2026-04-21T18:08:53.553068Z","end":"2026-04-21T18:08:53.763894Z","steps":["trace[838614067] 'process raft request' (duration: 210.604716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:55.162157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.215565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T18:08:55.162237Z","caller":"traceutil/trace.go:171","msg":"trace[2064747272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"447.324081ms","start":"2026-04-21T18:08:54.714891Z","end":"2026-04-21T18:08:55.162215Z","steps":["trace[2064747272] 'range keys from in-memory index tree' (duration: 447.084314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:55.162262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.034009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T18:08:55.162345Z","caller":"traceutil/trace.go:171","msg":"trace[537336610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"446.197532ms","start":"2026-04-21T18:08:54.716127Z","end":"2026-04-21T18:08:55.162324Z","steps":["trace[537336610] 'range keys from in-memory index tree' (duration: 445.843831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:55.162398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:54.716104Z","time spent":"446.281933ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T18:08:55.162396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.327567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:08:55.163242Z","caller":"traceutil/trace.go:171","msg":"trace[1684345539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"449.173622ms","start":"2026-04-21T18:08:54.714058Z","end":"2026-04-21T18:08:55.163232Z","steps":["trace[1684345539] 'range keys from in-memory index tree' (duration: 448.204895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:55.163274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:54.714037Z","time spent":"449.22726ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T18:08:55.162288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:54.714881Z","time spent":"447.394616ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T18:08:55.162178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.040548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:08:55.163432Z","caller":"traceutil/trace.go:171","msg":"trace[1707642896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"225.328646ms","start":"2026-04-21T18:08:54.938085Z","end":"2026-04-21T18:08:55.163414Z","steps":["trace[1707642896] 'range keys from in-memory index tree' (duration: 223.958967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:55.162449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.438122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T18:08:55.163496Z","caller":"traceutil/trace.go:171","msg":"trace[1263289293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"449.492901ms","start":"2026-04-21T18:08:54.713984Z","end":"2026-04-21T18:08:55.163477Z","steps":["trace[1263289293] 'range keys from in-memory index tree' (duration: 448.204394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:08:55.163547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:08:54.71393Z","time spent":"449.600307ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T18:08:55.809088Z","caller":"traceutil/trace.go:171","msg":"trace[996446354] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"124.853615ms","start":"2026-04-21T18:08:55.684214Z","end":"2026-04-21T18:08:55.809068Z","steps":["trace[996446354] 'process raft request' (duration: 124.726353ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:55.890988Z","caller":"traceutil/trace.go:171","msg":"trace[1167886144] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"165.231205ms","start":"2026-04-21T18:08:55.725735Z","end":"2026-04-21T18:08:55.890966Z","steps":["trace[1167886144] 'process raft request' (duration: 164.361517ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:55.890998Z","caller":"traceutil/trace.go:171","msg":"trace[1140870734] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"102.215362ms","start":"2026-04-21T18:08:55.788754Z","end":"2026-04-21T18:08:55.890969Z","steps":["trace[1140870734] 'process raft request' (duration: 102.178451ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:55.891202Z","caller":"traceutil/trace.go:171","msg":"trace[860925155] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"146.129087ms","start":"2026-04-21T18:08:55.745063Z","end":"2026-04-21T18:08:55.891192Z","steps":["trace[860925155] 'process raft request' (duration: 145.831772ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:08:58.141934Z","caller":"traceutil/trace.go:171","msg":"trace[1741131120] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"144.442976ms","start":"2026-04-21T18:08:57.997395Z","end":"2026-04-21T18:08:58.141838Z","steps":["trace[1741131120] 'process raft request' (duration: 140.413578ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:03.434458Z","caller":"traceutil/trace.go:171","msg":"trace[1891201193] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"134.828738ms","start":"2026-04-21T18:09:03.299598Z","end":"2026-04-21T18:09:03.434427Z","steps":["trace[1891201193] 'process raft request' (duration: 134.777039ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:03.434954Z","caller":"traceutil/trace.go:171","msg":"trace[587273125] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"136.204198ms","start":"2026-04-21T18:09:03.29873Z","end":"2026-04-21T18:09:03.434934Z","steps":["trace[587273125] 'process raft request' (duration: 69.280721ms)","trace[587273125] 'compare' (duration: 65.941131ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:09:03.435172Z","caller":"traceutil/trace.go:171","msg":"trace[508917649] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"136.111454ms","start":"2026-04-21T18:09:03.299045Z","end":"2026-04-21T18:09:03.435157Z","steps":["trace[508917649] 'process raft request' (duration: 135.254734ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:03.929772Z","caller":"traceutil/trace.go:171","msg":"trace[1090644869] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"112.463375ms","start":"2026-04-21T18:09:03.817273Z","end":"2026-04-21T18:09:03.929737Z","steps":["trace[1090644869] 'process raft request' (duration: 110.923089ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:04.092122Z","caller":"traceutil/trace.go:171","msg":"trace[1306293449] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:733; }","duration":"152.404566ms","start":"2026-04-21T18:09:03.939679Z","end":"2026-04-21T18:09:04.092084Z","steps":["trace[1306293449] 'read index received' (duration: 110.745103ms)","trace[1306293449] 'applied index is now lower than readState.Index' (duration: 41.658133ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:09:04.092212Z","caller":"traceutil/trace.go:171","msg":"trace[390273724] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"152.712555ms","start":"2026-04-21T18:09:03.939466Z","end":"2026-04-21T18:09:04.092178Z","steps":["trace[390273724] 'process raft request' (duration: 110.942294ms)","trace[390273724] 'compare' (duration: 41.492556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:09:04.092311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.598099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:09:04.092359Z","caller":"traceutil/trace.go:171","msg":"trace[336355423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"152.680872ms","start":"2026-04-21T18:09:03.939665Z","end":"2026-04-21T18:09:04.092346Z","steps":["trace[336355423] 'agreement among raft nodes before linearized reading' (duration: 152.565704ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:04.096842Z","caller":"traceutil/trace.go:171","msg":"trace[1216311720] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"149.133386ms","start":"2026-04-21T18:09:03.947685Z","end":"2026-04-21T18:09:04.096818Z","steps":["trace[1216311720] 'process raft request' (duration: 148.891262ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:04.318755Z","caller":"traceutil/trace.go:171","msg":"trace[1371555698] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"128.645062ms","start":"2026-04-21T18:09:04.190084Z","end":"2026-04-21T18:09:04.318729Z","steps":["trace[1371555698] 'process raft request' (duration: 128.488532ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:06.968129Z","caller":"traceutil/trace.go:171","msg":"trace[1025766407] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"165.391252ms","start":"2026-04-21T18:09:06.802704Z","end":"2026-04-21T18:09:06.968096Z","steps":["trace[1025766407] 'process raft request' (duration: 165.210846ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:08.093451Z","caller":"traceutil/trace.go:171","msg":"trace[2084898951] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"193.089627ms","start":"2026-04-21T18:09:07.90032Z","end":"2026-04-21T18:09:08.09341Z","steps":["trace[2084898951] 'read index received' (duration: 192.849101ms)","trace[2084898951] 'applied index is now lower than readState.Index' (duration: 238.706µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:09:08.093514Z","caller":"traceutil/trace.go:171","msg":"trace[1623489640] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"453.543702ms","start":"2026-04-21T18:09:07.639931Z","end":"2026-04-21T18:09:08.093475Z","steps":["trace[1623489640] 'process raft request' (duration: 453.241497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:08.093683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.321044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:09:08.093777Z","caller":"traceutil/trace.go:171","msg":"trace[1220107227] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:735; }","duration":"193.446251ms","start":"2026-04-21T18:09:07.900312Z","end":"2026-04-21T18:09:08.093758Z","steps":["trace[1220107227] 'agreement among raft nodes before linearized reading' (duration: 193.243909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:08.093831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:09:07.639908Z","time spent":"453.732907ms","remote":"127.0.0.1:45746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5032,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:09:09.719389Z","caller":"traceutil/trace.go:171","msg":"trace[2093048220] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"193.637503ms","start":"2026-04-21T18:09:09.525716Z","end":"2026-04-21T18:09:09.719353Z","steps":["trace[2093048220] 'read index received' (duration: 193.394047ms)","trace[2093048220] 'applied index is now lower than readState.Index' (duration: 241.996µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:09:09.71957Z","caller":"traceutil/trace.go:171","msg":"trace[32852740] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"215.785956ms","start":"2026-04-21T18:09:09.503743Z","end":"2026-04-21T18:09:09.719529Z","steps":["trace[32852740] 'process raft request' (duration: 215.389229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:09.719902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.700896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-21T18:09:09.719902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.084226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T18:09:09.719969Z","caller":"traceutil/trace.go:171","msg":"trace[28948838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:744; }","duration":"159.834824ms","start":"2026-04-21T18:09:09.560117Z","end":"2026-04-21T18:09:09.719952Z","steps":["trace[28948838] 'agreement among raft nodes before linearized reading' (duration: 159.605145ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:09.719999Z","caller":"traceutil/trace.go:171","msg":"trace[264881573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:744; }","duration":"189.218813ms","start":"2026-04-21T18:09:09.530748Z","end":"2026-04-21T18:09:09.719967Z","steps":["trace[264881573] 'agreement among raft nodes before linearized reading' (duration: 188.874155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:09.720149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.107936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T18:09:09.720849Z","caller":"traceutil/trace.go:171","msg":"trace[1691173398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:744; }","duration":"195.149155ms","start":"2026-04-21T18:09:09.525668Z","end":"2026-04-21T18:09:09.720817Z","steps":["trace[1691173398] 'agreement among raft nodes before linearized reading' (duration: 193.871359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:13.225242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038334886693313153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:09:13.726359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038334886693313153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:09:13.73689Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.029569898s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T18:09:13.737313Z","caller":"traceutil/trace.go:171","msg":"trace[1979534916] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"1.030109604s","start":"2026-04-21T18:09:12.707176Z","end":"2026-04-21T18:09:13.737286Z","steps":["trace[1979534916] 'process raft request' (duration: 1.029935004s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:13.737578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:09:12.707147Z","time spent":"1.030316427s","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:09:13.846875Z","caller":"traceutil/trace.go:171","msg":"trace[483584435] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:791; }","duration":"1.122324827s","start":"2026-04-21T18:09:12.72453Z","end":"2026-04-21T18:09:13.846854Z","steps":["trace[483584435] 'read index received' (duration: 1.012516564s)","trace[483584435] 'applied index is now lower than readState.Index' (duration: 109.806933ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:09:13.846984Z","caller":"traceutil/trace.go:171","msg":"trace[1073602034] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"1.100411958s","start":"2026-04-21T18:09:12.74656Z","end":"2026-04-21T18:09:13.846972Z","steps":["trace[1073602034] 'process raft request' (duration: 1.100059909s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:13.84704Z","caller":"traceutil/trace.go:171","msg":"trace[1033691701] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"313.137972ms","start":"2026-04-21T18:09:13.533884Z","end":"2026-04-21T18:09:13.847022Z","steps":["trace[1033691701] 'process raft request' (duration: 312.929778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:13.847098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:09:12.746533Z","time spent":"1.100482356s","remote":"127.0.0.1:45740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5052,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:09:13.847132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:09:13.533861Z","time spent":"313.224087ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:09:13.847252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122725567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T18:09:13.847291Z","caller":"traceutil/trace.go:171","msg":"trace[1246409949] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:766; }","duration":"1.122839567s","start":"2026-04-21T18:09:12.72444Z","end":"2026-04-21T18:09:13.84728Z","steps":["trace[1246409949] 'agreement among raft nodes before linearized reading' (duration: 1.122752542s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:13.847321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:09:12.724418Z","time spent":"1.122895627s","remote":"127.0.0.1:45684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-21T18:09:13.847394Z","caller":"traceutil/trace.go:171","msg":"trace[348559863] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"723.063092ms","start":"2026-04-21T18:09:13.12432Z","end":"2026-04-21T18:09:13.847383Z","steps":["trace[348559863] 'process raft request' (duration: 722.422684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:13.847462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:09:13.124288Z","time spent":"723.138259ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:09:13.847513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.327405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:09:13.847547Z","caller":"traceutil/trace.go:171","msg":"trace[1294100606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"904.402562ms","start":"2026-04-21T18:09:12.943136Z","end":"2026-04-21T18:09:13.847538Z","steps":["trace[1294100606] 'agreement among raft nodes before linearized reading' (duration: 904.353001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:13.847577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:09:12.94311Z","time spent":"904.461422ms","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T18:09:13.847958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.115209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:09:13.848005Z","caller":"traceutil/trace.go:171","msg":"trace[383988670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"484.202584ms","start":"2026-04-21T18:09:13.36379Z","end":"2026-04-21T18:09:13.847993Z","steps":["trace[383988670] 'agreement among raft nodes before linearized reading' (duration: 484.084985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:13.848039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:09:13.363741Z","time spent":"484.29061ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T18:09:28.926151Z","caller":"traceutil/trace.go:171","msg":"trace[1844213571] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"198.6847ms","start":"2026-04-21T18:09:28.727446Z","end":"2026-04-21T18:09:28.926131Z","steps":["trace[1844213571] 'process raft request' (duration: 198.501397ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:29.006372Z","caller":"traceutil/trace.go:171","msg":"trace[1265976999] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"150.623582ms","start":"2026-04-21T18:09:28.85573Z","end":"2026-04-21T18:09:29.006354Z","steps":["trace[1265976999] 'process raft request' (duration: 148.832117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:38.631907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.205987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:09:38.632064Z","caller":"traceutil/trace.go:171","msg":"trace[386970634] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"316.173821ms","start":"2026-04-21T18:09:38.315845Z","end":"2026-04-21T18:09:38.632019Z","steps":["trace[386970634] 'process raft request' (duration: 88.709446ms)","trace[386970634] 'compare' (duration: 226.884229ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:09:38.632153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:09:38.315834Z","time spent":"316.288795ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:09:38.648403Z","caller":"traceutil/trace.go:171","msg":"trace[243279397] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"330.855804ms","start":"2026-04-21T18:09:38.317532Z","end":"2026-04-21T18:09:38.648388Z","steps":["trace[243279397] 'process raft request' (duration: 330.65105ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:38.64839Z","caller":"traceutil/trace.go:171","msg":"trace[546939470] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"328.388651ms","start":"2026-04-21T18:09:38.319975Z","end":"2026-04-21T18:09:38.648363Z","steps":["trace[546939470] 'process raft request' (duration: 328.336138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:38.648522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:09:38.317519Z","time spent":"330.933384ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:09:38.648783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:09:38.319944Z","time spent":"328.63197ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:09:39.304152Z","caller":"traceutil/trace.go:171","msg":"trace[1822320241] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"168.247328ms","start":"2026-04-21T18:09:39.135877Z","end":"2026-04-21T18:09:39.304124Z","steps":["trace[1822320241] 'process raft request' (duration: 168.090648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:40.428149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.93984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:09:40.428206Z","caller":"traceutil/trace.go:171","msg":"trace[1269363761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:978; }","duration":"123.062044ms","start":"2026-04-21T18:09:40.305131Z","end":"2026-04-21T18:09:40.428193Z","steps":["trace[1269363761] 'range keys from in-memory index tree' (duration: 122.745454ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:40.836852Z","caller":"traceutil/trace.go:171","msg":"trace[630455503] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"153.268474ms","start":"2026-04-21T18:09:40.683546Z","end":"2026-04-21T18:09:40.836815Z","steps":["trace[630455503] 'process raft request' (duration: 152.992348ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:42.929529Z","caller":"traceutil/trace.go:171","msg":"trace[1516363505] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"102.855911ms","start":"2026-04-21T18:09:42.826646Z","end":"2026-04-21T18:09:42.929502Z","steps":["trace[1516363505] 'process raft request' (duration: 102.694941ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:43.527648Z","caller":"traceutil/trace.go:171","msg":"trace[890807943] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"210.015657ms","start":"2026-04-21T18:09:43.317607Z","end":"2026-04-21T18:09:43.527623Z","steps":["trace[890807943] 'process raft request' (duration: 209.851497ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:47.699727Z","caller":"traceutil/trace.go:171","msg":"trace[317312231] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"126.987425ms","start":"2026-04-21T18:09:47.572709Z","end":"2026-04-21T18:09:47.699696Z","steps":["trace[317312231] 'process raft request' (duration: 126.842852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:47.861245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.035714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T18:09:47.861311Z","caller":"traceutil/trace.go:171","msg":"trace[1656899700] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1008; }","duration":"101.15981ms","start":"2026-04-21T18:09:47.760137Z","end":"2026-04-21T18:09:47.861297Z","steps":["trace[1656899700] 'count revisions from in-memory index tree' (duration: 100.940525ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:53.225759Z","caller":"traceutil/trace.go:171","msg":"trace[244989803] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"106.213898ms","start":"2026-04-21T18:09:53.119526Z","end":"2026-04-21T18:09:53.22574Z","steps":["trace[244989803] 'read index received' (duration: 106.000481ms)","trace[244989803] 'applied index is now lower than readState.Index' (duration: 212.557µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:09:53.225978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.434444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-21T18:09:53.226032Z","caller":"traceutil/trace.go:171","msg":"trace[1996095647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1025; }","duration":"106.542422ms","start":"2026-04-21T18:09:53.119474Z","end":"2026-04-21T18:09:53.226016Z","steps":["trace[1996095647] 'agreement among raft nodes before linearized reading' (duration: 106.352983ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:53.226328Z","caller":"traceutil/trace.go:171","msg":"trace[1750159935] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"232.383448ms","start":"2026-04-21T18:09:52.993932Z","end":"2026-04-21T18:09:53.226316Z","steps":["trace[1750159935] 'process raft request' (duration: 231.689793ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:57.431648Z","caller":"traceutil/trace.go:171","msg":"trace[2142347691] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"178.393954ms","start":"2026-04-21T18:09:57.253222Z","end":"2026-04-21T18:09:57.431616Z","steps":["trace[2142347691] 'process raft request' (duration: 161.60453ms)","trace[2142347691] 'compare' (duration: 16.655638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:10:09.038319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.583786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:10:09.038362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.096731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-21T18:10:09.038563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.643718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T18:10:09.038409Z","caller":"traceutil/trace.go:171","msg":"trace[505183158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"101.750981ms","start":"2026-04-21T18:10:08.936634Z","end":"2026-04-21T18:10:09.038385Z","steps":["trace[505183158] 'range keys from in-memory index tree' (duration: 101.376856ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:09.038663Z","caller":"traceutil/trace.go:171","msg":"trace[873757418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1076; }","duration":"154.791044ms","start":"2026-04-21T18:10:08.883853Z","end":"2026-04-21T18:10:09.038644Z","steps":["trace[873757418] 'range keys from in-memory index tree' (duration: 154.380783ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:09.038712Z","caller":"traceutil/trace.go:171","msg":"trace[1837701418] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1076; }","duration":"136.390153ms","start":"2026-04-21T18:10:08.90221Z","end":"2026-04-21T18:10:09.0386Z","steps":["trace[1837701418] 'count revisions from in-memory index tree' (duration: 136.045126ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:10.714188Z","caller":"traceutil/trace.go:171","msg":"trace[1923519734] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"193.582023ms","start":"2026-04-21T18:10:10.520193Z","end":"2026-04-21T18:10:10.713775Z","steps":["trace[1923519734] 'process raft request' (duration: 193.424457ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:12.936487Z","caller":"traceutil/trace.go:171","msg":"trace[172745264] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"215.035411ms","start":"2026-04-21T18:10:12.721428Z","end":"2026-04-21T18:10:12.936464Z","steps":["trace[172745264] 'process raft request' (duration: 214.668273ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:15.326836Z","caller":"traceutil/trace.go:171","msg":"trace[551491409] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"347.689342ms","start":"2026-04-21T18:10:14.979114Z","end":"2026-04-21T18:10:15.326803Z","steps":["trace[551491409] 'read index received' (duration: 347.547794ms)","trace[551491409] 'applied index is now lower than readState.Index' (duration: 140.278µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:15.326962Z","caller":"traceutil/trace.go:171","msg":"trace[962851565] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"383.902051ms","start":"2026-04-21T18:10:14.943017Z","end":"2026-04-21T18:10:15.326919Z","steps":["trace[962851565] 'process raft request' (duration: 383.600647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:15.327166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:14.942997Z","time spent":"384.047858ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:10:15.327168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.031732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:10:15.327229Z","caller":"traceutil/trace.go:171","msg":"trace[1801257517] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1094; }","duration":"348.16194ms","start":"2026-04-21T18:10:14.979054Z","end":"2026-04-21T18:10:15.327216Z","steps":["trace[1801257517] 'agreement among raft nodes before linearized reading' (duration: 347.93805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:15.327286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:14.97903Z","time spent":"348.247123ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T18:10:15.419182Z","caller":"traceutil/trace.go:171","msg":"trace[165104017] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"241.050342ms","start":"2026-04-21T18:10:15.178092Z","end":"2026-04-21T18:10:15.419143Z","steps":["trace[165104017] 'process raft request' (duration: 226.246486ms)","trace[165104017] 'compare' (duration: 14.633001ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:15.419413Z","caller":"traceutil/trace.go:171","msg":"trace[547156405] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"198.201759ms","start":"2026-04-21T18:10:15.221189Z","end":"2026-04-21T18:10:15.41939Z","steps":["trace[547156405] 'process raft request' (duration: 197.885217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:15.491353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.481646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-21T18:10:15.491417Z","caller":"traceutil/trace.go:171","msg":"trace[2024128964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1097; }","duration":"266.578538ms","start":"2026-04-21T18:10:15.224824Z","end":"2026-04-21T18:10:15.491403Z","steps":["trace[2024128964] 'agreement among raft nodes before linearized reading' (duration: 194.541636ms)","trace[2024128964] 'range keys from in-memory index tree' (duration: 71.889045ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:15.897745Z","caller":"traceutil/trace.go:171","msg":"trace[805846689] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1139; }","duration":"370.353887ms","start":"2026-04-21T18:10:15.527367Z","end":"2026-04-21T18:10:15.897721Z","steps":["trace[805846689] 'read index received' (duration: 37.295415ms)","trace[805846689] 'applied index is now lower than readState.Index' (duration: 333.057102ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:10:15.89805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.661971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T18:10:15.89813Z","caller":"traceutil/trace.go:171","msg":"trace[1626343335] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1099; }","duration":"370.822446ms","start":"2026-04-21T18:10:15.527296Z","end":"2026-04-21T18:10:15.898119Z","steps":["trace[1626343335] 'agreement among raft nodes before linearized reading' (duration: 370.6679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:15.89817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:15.527277Z","time spent":"370.877211ms","remote":"127.0.0.1:45930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":54,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-21T18:10:15.898178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.792222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T18:10:15.89828Z","caller":"traceutil/trace.go:171","msg":"trace[844150647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1099; }","duration":"270.945169ms","start":"2026-04-21T18:10:15.627307Z","end":"2026-04-21T18:10:15.898252Z","steps":["trace[844150647] 'agreement among raft nodes before linearized reading' (duration: 270.631866ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:15.897861Z","caller":"traceutil/trace.go:171","msg":"trace[817478] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"402.26199ms","start":"2026-04-21T18:10:15.495586Z","end":"2026-04-21T18:10:15.897848Z","steps":["trace[817478] 'process raft request' (duration: 361.958895ms)","trace[817478] 'compare' (duration: 39.850284ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:10:15.898585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:15.495573Z","time spent":"402.95043ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:10:18.114729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.199417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:10:18.114849Z","caller":"traceutil/trace.go:171","msg":"trace[1905043195] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"170.419025ms","start":"2026-04-21T18:10:17.944406Z","end":"2026-04-21T18:10:18.114825Z","steps":["trace[1905043195] 'process raft request' (duration: 64.043863ms)","trace[1905043195] 'compare' (duration: 106.071678ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:18.171946Z","caller":"traceutil/trace.go:171","msg":"trace[1763142268] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"114.549683ms","start":"2026-04-21T18:10:18.057043Z","end":"2026-04-21T18:10:18.171592Z","steps":["trace[1763142268] 'process raft request' (duration: 114.396476ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:19.830402Z","caller":"traceutil/trace.go:171","msg":"trace[2027865173] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"140.006847ms","start":"2026-04-21T18:10:19.69037Z","end":"2026-04-21T18:10:19.830376Z","steps":["trace[2027865173] 'process raft request' (duration: 139.756968ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:20.07939Z","caller":"traceutil/trace.go:171","msg":"trace[1473619110] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"125.413085ms","start":"2026-04-21T18:10:19.953944Z","end":"2026-04-21T18:10:20.079357Z","steps":["trace[1473619110] 'process raft request' (duration: 111.185761ms)","trace[1473619110] 'compare' (duration: 13.809068ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:20.079638Z","caller":"traceutil/trace.go:171","msg":"trace[1451512567] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"105.764705ms","start":"2026-04-21T18:10:19.973863Z","end":"2026-04-21T18:10:20.079628Z","steps":["trace[1451512567] 'process raft request' (duration: 105.359099ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:22.367681Z","caller":"traceutil/trace.go:171","msg":"trace[2146925039] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"142.145882ms","start":"2026-04-21T18:10:22.225501Z","end":"2026-04-21T18:10:22.367647Z","steps":["trace[2146925039] 'process raft request' (duration: 141.996864ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:22.368051Z","caller":"traceutil/trace.go:171","msg":"trace[1666704772] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"182.588777ms","start":"2026-04-21T18:10:22.185439Z","end":"2026-04-21T18:10:22.368027Z","steps":["trace[1666704772] 'process raft request' (duration: 83.486858ms)","trace[1666704772] 'compare' (duration: 98.040046ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:24.123437Z","caller":"traceutil/trace.go:171","msg":"trace[226943122] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1165; }","duration":"183.9481ms","start":"2026-04-21T18:10:23.939462Z","end":"2026-04-21T18:10:24.12341Z","steps":["trace[226943122] 'read index received' (duration: 183.6967ms)","trace[226943122] 'applied index is now lower than readState.Index' (duration: 250.26µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:24.123534Z","caller":"traceutil/trace.go:171","msg":"trace[1495853972] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"201.071501ms","start":"2026-04-21T18:10:23.922413Z","end":"2026-04-21T18:10:24.123485Z","steps":["trace[1495853972] 'process raft request' (duration: 200.83252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:24.123646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.122316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:10:24.123728Z","caller":"traceutil/trace.go:171","msg":"trace[1430381801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1123; }","duration":"184.273184ms","start":"2026-04-21T18:10:23.939436Z","end":"2026-04-21T18:10:24.123709Z","steps":["trace[1430381801] 'agreement among raft nodes before linearized reading' (duration: 184.097208ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:24.556452Z","caller":"traceutil/trace.go:171","msg":"trace[565376256] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"143.578627ms","start":"2026-04-21T18:10:24.412842Z","end":"2026-04-21T18:10:24.556421Z","steps":["trace[565376256] 'process raft request' (duration: 136.789148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:25.756944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.804853ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-21T18:10:25.757157Z","caller":"traceutil/trace.go:171","msg":"trace[1758250200] linearizableReadLoop","detail":"{readStateIndex:1170; appliedIndex:1169; }","duration":"319.667809ms","start":"2026-04-21T18:10:25.437468Z","end":"2026-04-21T18:10:25.757136Z","steps":["trace[1758250200] 'read index received' (duration: 101.844189ms)","trace[1758250200] 'applied index is now lower than readState.Index' (duration: 217.82228ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:10:25.757334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.856344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T18:10:25.75736Z","caller":"traceutil/trace.go:171","msg":"trace[713590714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1126; }","duration":"319.923898ms","start":"2026-04-21T18:10:25.43743Z","end":"2026-04-21T18:10:25.757354Z","steps":["trace[713590714] 'agreement among raft nodes before linearized reading' (duration: 319.812387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:25.75738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:25.437411Z","time spent":"319.964935ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T18:10:25.757681Z","caller":"traceutil/trace.go:171","msg":"trace[808584185] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"259.623537ms","start":"2026-04-21T18:10:25.498038Z","end":"2026-04-21T18:10:25.757662Z","steps":["trace[808584185] 'process raft request' (duration: 259.004095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:25.954285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.37948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:10:25.954373Z","caller":"traceutil/trace.go:171","msg":"trace[170183105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1127; }","duration":"274.508689ms","start":"2026-04-21T18:10:25.679842Z","end":"2026-04-21T18:10:25.954351Z","steps":["trace[170183105] 'agreement among raft nodes before linearized reading' (duration: 77.79897ms)","trace[170183105] 'range keys from in-memory index tree' (duration: 196.489276ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:26.02398Z","caller":"traceutil/trace.go:171","msg":"trace[1377333352] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"263.715178ms","start":"2026-04-21T18:10:25.760247Z","end":"2026-04-21T18:10:26.023963Z","steps":["trace[1377333352] 'process raft request' (duration: 263.602707ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:26.109695Z","caller":"traceutil/trace.go:171","msg":"trace[1798192506] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1171; }","duration":"171.490007ms","start":"2026-04-21T18:10:25.938188Z","end":"2026-04-21T18:10:26.109678Z","steps":["trace[1798192506] 'read index received' (duration: 85.745015ms)","trace[1798192506] 'applied index is now lower than readState.Index' (duration: 85.744202ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:10:26.109815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.620107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:10:26.109787Z","caller":"traceutil/trace.go:171","msg":"trace[513951986] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"290.247239ms","start":"2026-04-21T18:10:25.8195Z","end":"2026-04-21T18:10:26.109747Z","steps":["trace[513951986] 'process raft request' (duration: 272.631729ms)","trace[513951986] 'compare' (duration: 17.397788ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:26.10984Z","caller":"traceutil/trace.go:171","msg":"trace[1929558666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1130; }","duration":"171.700692ms","start":"2026-04-21T18:10:25.938131Z","end":"2026-04-21T18:10:26.109831Z","steps":["trace[1929558666] 'agreement among raft nodes before linearized reading' (duration: 171.624568ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:26.109916Z","caller":"traceutil/trace.go:171","msg":"trace[603142741] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"151.702302ms","start":"2026-04-21T18:10:25.958199Z","end":"2026-04-21T18:10:26.109902Z","steps":["trace[603142741] 'process raft request' (duration: 151.437253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:26.380467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.828258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T18:10:26.380555Z","caller":"traceutil/trace.go:171","msg":"trace[1633151257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1131; }","duration":"159.957867ms","start":"2026-04-21T18:10:26.220573Z","end":"2026-04-21T18:10:26.380531Z","steps":["trace[1633151257] 'range keys from in-memory index tree' (duration: 159.66843ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:26.579066Z","caller":"traceutil/trace.go:171","msg":"trace[17164710] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"164.529679ms","start":"2026-04-21T18:10:26.414506Z","end":"2026-04-21T18:10:26.579036Z","steps":["trace[17164710] 'process raft request' (duration: 78.934605ms)","trace[17164710] 'compare' (duration: 85.404319ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:32.80562Z","caller":"traceutil/trace.go:171","msg":"trace[172223019] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"168.408318ms","start":"2026-04-21T18:10:32.637185Z","end":"2026-04-21T18:10:32.805594Z","steps":["trace[172223019] 'process raft request' (duration: 167.675242ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:38.340137Z","caller":"traceutil/trace.go:171","msg":"trace[1851913273] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"113.970471ms","start":"2026-04-21T18:10:38.226132Z","end":"2026-04-21T18:10:38.340102Z","steps":["trace[1851913273] 'process raft request' (duration: 113.612836ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:38.569554Z","caller":"traceutil/trace.go:171","msg":"trace[636974395] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"174.561826ms","start":"2026-04-21T18:10:38.394941Z","end":"2026-04-21T18:10:38.569503Z","steps":["trace[636974395] 'process raft request' (duration: 118.061182ms)","trace[636974395] 'compare' (duration: 56.315187ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:40.081711Z","caller":"traceutil/trace.go:171","msg":"trace[1052397889] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"108.212804ms","start":"2026-04-21T18:10:39.973463Z","end":"2026-04-21T18:10:40.081676Z","steps":["trace[1052397889] 'process raft request' (duration: 39.855949ms)","trace[1052397889] 'compare' (duration: 68.042767ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:10:40.865797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038334886693314115,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T18:10:41.077055Z","caller":"traceutil/trace.go:171","msg":"trace[1042971059] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"729.482007ms","start":"2026-04-21T18:10:40.347542Z","end":"2026-04-21T18:10:41.077024Z","steps":["trace[1042971059] 'process raft request' (duration: 729.300449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:41.077269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:40.34752Z","time spent":"729.624786ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:10:41.110816Z","caller":"traceutil/trace.go:171","msg":"trace[1512690508] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1221; }","duration":"745.530888ms","start":"2026-04-21T18:10:40.365264Z","end":"2026-04-21T18:10:41.110795Z","steps":["trace[1512690508] 'read index received' (duration: 711.602281ms)","trace[1512690508] 'applied index is now lower than readState.Index' (duration: 33.927377ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:41.110902Z","caller":"traceutil/trace.go:171","msg":"trace[366436712] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"265.774658ms","start":"2026-04-21T18:10:40.845095Z","end":"2026-04-21T18:10:41.11087Z","steps":["trace[366436712] 'process raft request' (duration: 265.60302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:41.111149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.51345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-21T18:10:41.111151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.372197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:10:41.111189Z","caller":"traceutil/trace.go:171","msg":"trace[945971666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1176; }","duration":"512.612034ms","start":"2026-04-21T18:10:40.598568Z","end":"2026-04-21T18:10:41.11118Z","steps":["trace[945971666] 'agreement among raft nodes before linearized reading' (duration: 512.472303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:41.111218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:40.598533Z","time spent":"512.677089ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T18:10:41.111319Z","caller":"traceutil/trace.go:171","msg":"trace[895941000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1176; }","duration":"716.46701ms","start":"2026-04-21T18:10:40.394723Z","end":"2026-04-21T18:10:41.11119Z","steps":["trace[895941000] 'agreement among raft nodes before linearized reading' (duration: 716.217187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:41.111332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.301602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:10:41.111423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.323775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-21T18:10:41.111464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:40.394695Z","time spent":"716.693675ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T18:10:41.111464Z","caller":"traceutil/trace.go:171","msg":"trace[1225781475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1176; }","duration":"172.545026ms","start":"2026-04-21T18:10:40.938887Z","end":"2026-04-21T18:10:41.111432Z","steps":["trace[1225781475] 'agreement among raft nodes before linearized reading' (duration: 172.274374ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:41.111526Z","caller":"traceutil/trace.go:171","msg":"trace[1125741726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1176; }","duration":"537.480424ms","start":"2026-04-21T18:10:40.57402Z","end":"2026-04-21T18:10:41.1115Z","steps":["trace[1125741726] 'agreement among raft nodes before linearized reading' (duration: 537.178394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:41.111582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:40.573965Z","time spent":"537.608505ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T18:10:41.111974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.875176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-21T18:10:41.112114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.837168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-04-21T18:10:41.11219Z","caller":"traceutil/trace.go:171","msg":"trace[1093260570] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1176; }","duration":"746.9515ms","start":"2026-04-21T18:10:40.365221Z","end":"2026-04-21T18:10:41.112173Z","steps":["trace[1093260570] 'agreement among raft nodes before linearized reading' (duration: 745.697897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:41.112246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:40.365205Z","time spent":"747.023884ms","remote":"127.0.0.1:45674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050288,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-21T18:10:41.112268Z","caller":"traceutil/trace.go:171","msg":"trace[2007421072] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1176; }","duration":"231.361732ms","start":"2026-04-21T18:10:40.880795Z","end":"2026-04-21T18:10:41.112157Z","steps":["trace[2007421072] 'agreement among raft nodes before linearized reading' (duration: 230.2349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:41.2863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.237808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:10:41.286538Z","caller":"traceutil/trace.go:171","msg":"trace[1504327727] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"168.973068ms","start":"2026-04-21T18:10:41.117538Z","end":"2026-04-21T18:10:41.286512Z","steps":["trace[1504327727] 'process raft request' (duration: 41.429ms)","trace[1504327727] 'compare' (duration: 127.035973ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:41.320126Z","caller":"traceutil/trace.go:171","msg":"trace[578487927] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"202.466276ms","start":"2026-04-21T18:10:41.117649Z","end":"2026-04-21T18:10:41.320115Z","steps":["trace[578487927] 'process raft request' (duration: 202.292967ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:41.320157Z","caller":"traceutil/trace.go:171","msg":"trace[1393177246] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"202.504593ms","start":"2026-04-21T18:10:41.117647Z","end":"2026-04-21T18:10:41.320151Z","steps":["trace[1393177246] 'process raft request' (duration: 202.387721ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:41.320112Z","caller":"traceutil/trace.go:171","msg":"trace[1379800610] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"202.40103ms","start":"2026-04-21T18:10:41.11769Z","end":"2026-04-21T18:10:41.320091Z","steps":["trace[1379800610] 'process raft request' (duration: 202.371842ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:43.513805Z","caller":"traceutil/trace.go:171","msg":"trace[1703574002] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"184.859022ms","start":"2026-04-21T18:10:43.328929Z","end":"2026-04-21T18:10:43.513788Z","steps":["trace[1703574002] 'process raft request' (duration: 184.657036ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:43.513714Z","caller":"traceutil/trace.go:171","msg":"trace[1902233898] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"184.804995ms","start":"2026-04-21T18:10:43.32886Z","end":"2026-04-21T18:10:43.513665Z","steps":["trace[1902233898] 'process raft request' (duration: 168.176596ms)","trace[1902233898] 'compare' (duration: 16.122304ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:50.287127Z","caller":"traceutil/trace.go:171","msg":"trace[2053567234] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"307.948089ms","start":"2026-04-21T18:10:49.979152Z","end":"2026-04-21T18:10:50.2871Z","steps":["trace[2053567234] 'process raft request' (duration: 304.49768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:50.287472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:49.979132Z","time spent":"308.148266ms","remote":"127.0.0.1:45594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:10:57.128247Z","caller":"traceutil/trace.go:171","msg":"trace[1589520246] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"120.764467ms","start":"2026-04-21T18:10:57.00746Z","end":"2026-04-21T18:10:57.128224Z","steps":["trace[1589520246] 'process raft request' (duration: 120.623046ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:57.371156Z","caller":"traceutil/trace.go:171","msg":"trace[330710773] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"118.42262ms","start":"2026-04-21T18:10:57.252708Z","end":"2026-04-21T18:10:57.371131Z","steps":["trace[330710773] 'process raft request' (duration: 118.272329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:57.550466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.12528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:10:57.550653Z","caller":"traceutil/trace.go:171","msg":"trace[731792273] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1248; }","duration":"177.045462ms","start":"2026-04-21T18:10:57.373592Z","end":"2026-04-21T18:10:57.550638Z","steps":["trace[731792273] 'process raft request' (duration: 176.987956ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:57.550631Z","caller":"traceutil/trace.go:171","msg":"trace[1185164503] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"294.976922ms","start":"2026-04-21T18:10:57.255618Z","end":"2026-04-21T18:10:57.550595Z","steps":["trace[1185164503] 'process raft request' (duration: 193.640325ms)","trace[1185164503] 'compare' (duration: 100.977968ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:57.550955Z","caller":"traceutil/trace.go:171","msg":"trace[2125506314] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1296; }","duration":"286.700058ms","start":"2026-04-21T18:10:57.26401Z","end":"2026-04-21T18:10:57.55071Z","steps":["trace[2125506314] 'read index received' (duration: 107.064405ms)","trace[2125506314] 'applied index is now lower than readState.Index' (duration: 179.630004ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:10:57.551138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.061326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-21T18:10:57.551259Z","caller":"traceutil/trace.go:171","msg":"trace[508840267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1248; }","duration":"287.307861ms","start":"2026-04-21T18:10:57.263927Z","end":"2026-04-21T18:10:57.551235Z","steps":["trace[508840267] 'agreement among raft nodes before linearized reading' (duration: 286.918825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:57.551456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.026283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-21T18:10:57.551558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.108145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T18:10:57.551658Z","caller":"traceutil/trace.go:171","msg":"trace[200030334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1248; }","duration":"176.142465ms","start":"2026-04-21T18:10:57.375358Z","end":"2026-04-21T18:10:57.5515Z","steps":["trace[200030334] 'agreement among raft nodes before linearized reading' (duration: 175.815366ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:57.551625Z","caller":"traceutil/trace.go:171","msg":"trace[657450947] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1248; }","duration":"162.225638ms","start":"2026-04-21T18:10:57.389382Z","end":"2026-04-21T18:10:57.551608Z","steps":["trace[657450947] 'agreement among raft nodes before linearized reading' (duration: 162.02932ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:57.73398Z","caller":"traceutil/trace.go:171","msg":"trace[1068300104] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"115.865127ms","start":"2026-04-21T18:10:57.618073Z","end":"2026-04-21T18:10:57.733938Z","steps":["trace[1068300104] 'process raft request' (duration: 115.663909ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:57.938446Z","caller":"traceutil/trace.go:171","msg":"trace[614032378] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"198.78977ms","start":"2026-04-21T18:10:57.739627Z","end":"2026-04-21T18:10:57.938417Z","steps":["trace[614032378] 'process raft request' (duration: 183.645097ms)","trace[614032378] 'compare' (duration: 14.925686ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:57.982538Z","caller":"traceutil/trace.go:171","msg":"trace[84587143] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1306; }","duration":"196.844749ms","start":"2026-04-21T18:10:57.785666Z","end":"2026-04-21T18:10:57.982511Z","steps":["trace[84587143] 'read index received' (duration: 137.69467ms)","trace[84587143] 'applied index is now lower than readState.Index' (duration: 59.145799ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:57.982647Z","caller":"traceutil/trace.go:171","msg":"trace[1602701162] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"234.144675ms","start":"2026-04-21T18:10:57.74846Z","end":"2026-04-21T18:10:57.982605Z","steps":["trace[1602701162] 'process raft request' (duration: 233.916729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:57.982981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.293911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T18:10:57.983238Z","caller":"traceutil/trace.go:171","msg":"trace[991925373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"197.598583ms","start":"2026-04-21T18:10:57.78562Z","end":"2026-04-21T18:10:57.983219Z","steps":["trace[991925373] 'agreement among raft nodes before linearized reading' (duration: 197.062675ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:58.241417Z","caller":"traceutil/trace.go:171","msg":"trace[179210901] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"107.655924ms","start":"2026-04-21T18:10:58.1336Z","end":"2026-04-21T18:10:58.241256Z","steps":["trace[179210901] 'process raft request' (duration: 107.320745ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:58.241099Z","caller":"traceutil/trace.go:171","msg":"trace[1037950791] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"107.372263ms","start":"2026-04-21T18:10:58.1337Z","end":"2026-04-21T18:10:58.241072Z","steps":["trace[1037950791] 'read index received' (duration: 107.126728ms)","trace[1037950791] 'applied index is now lower than readState.Index' (duration: 244.225µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:10:58.242048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.322495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-04-21T18:10:58.242147Z","caller":"traceutil/trace.go:171","msg":"trace[1939611123] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1259; }","duration":"108.487705ms","start":"2026-04-21T18:10:58.133641Z","end":"2026-04-21T18:10:58.242129Z","steps":["trace[1939611123] 'agreement among raft nodes before linearized reading' (duration: 107.698122ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:58.360171Z","caller":"traceutil/trace.go:171","msg":"trace[1117408901] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"111.337721ms","start":"2026-04-21T18:10:58.248808Z","end":"2026-04-21T18:10:58.360146Z","steps":["trace[1117408901] 'process raft request' (duration: 49.097835ms)","trace[1117408901] 'compare' (duration: 61.960254ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:58.360705Z","caller":"traceutil/trace.go:171","msg":"trace[322934506] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"107.07978ms","start":"2026-04-21T18:10:58.253564Z","end":"2026-04-21T18:10:58.360644Z","steps":["trace[322934506] 'process raft request' (duration: 106.447209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:00.251422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.206486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:11:00.251611Z","caller":"traceutil/trace.go:171","msg":"trace[37721395] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1319; }","duration":"218.268142ms","start":"2026-04-21T18:11:00.033324Z","end":"2026-04-21T18:11:00.251592Z","steps":["trace[37721395] 'read index received' (duration: 201.069934ms)","trace[37721395] 'applied index is now lower than readState.Index' (duration: 17.197318ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:11:00.251842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.515437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T18:11:00.251889Z","caller":"traceutil/trace.go:171","msg":"trace[774612570] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1269; }","duration":"218.588072ms","start":"2026-04-21T18:11:00.033286Z","end":"2026-04-21T18:11:00.251875Z","steps":["trace[774612570] 'agreement among raft nodes before linearized reading' (duration: 218.377435ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:00.251949Z","caller":"traceutil/trace.go:171","msg":"trace[384237775] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"287.23727ms","start":"2026-04-21T18:10:59.964687Z","end":"2026-04-21T18:11:00.251924Z","steps":["trace[384237775] 'process raft request' (duration: 54.43112ms)","trace[384237775] 'compare' (duration: 232.076384ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:11:00.252006Z","caller":"traceutil/trace.go:171","msg":"trace[1700751224] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"284.10201ms","start":"2026-04-21T18:10:59.967881Z","end":"2026-04-21T18:11:00.251983Z","steps":["trace[1700751224] 'process raft request' (duration: 283.655097ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:00.878999Z","caller":"traceutil/trace.go:171","msg":"trace[1412090043] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"182.277519ms","start":"2026-04-21T18:11:00.696694Z","end":"2026-04-21T18:11:00.878972Z","steps":["trace[1412090043] 'process raft request' (duration: 182.120229ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:02.053034Z","caller":"traceutil/trace.go:171","msg":"trace[214796891] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"424.498327ms","start":"2026-04-21T18:11:01.628512Z","end":"2026-04-21T18:11:02.053011Z","steps":["trace[214796891] 'read index received' (duration: 424.26996ms)","trace[214796891] 'applied index is now lower than readState.Index' (duration: 227.537µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:11:02.053127Z","caller":"traceutil/trace.go:171","msg":"trace[335253938] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"431.060976ms","start":"2026-04-21T18:11:01.622034Z","end":"2026-04-21T18:11:02.053095Z","steps":["trace[335253938] 'process raft request' (duration: 430.811411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:02.053274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.745903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-21T18:11:02.053271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:01.622011Z","time spent":"431.174329ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:11:02.053301Z","caller":"traceutil/trace.go:171","msg":"trace[1891803413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1272; }","duration":"424.813868ms","start":"2026-04-21T18:11:01.628478Z","end":"2026-04-21T18:11:02.053292Z","steps":["trace[1891803413] 'agreement among raft nodes before linearized reading' (duration: 424.637589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:02.053291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.808964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-21T18:11:02.053327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:01.628461Z","time spent":"424.860676ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T18:11:02.053341Z","caller":"traceutil/trace.go:171","msg":"trace[1194719688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1272; }","duration":"416.88318ms","start":"2026-04-21T18:11:01.636444Z","end":"2026-04-21T18:11:02.053327Z","steps":["trace[1194719688] 'agreement among raft nodes before linearized reading' (duration: 416.738629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:02.05337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.539488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:11:02.053375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:01.636433Z","time spent":"416.933998ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T18:11:02.053466Z","caller":"traceutil/trace.go:171","msg":"trace[933029822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"114.693519ms","start":"2026-04-21T18:11:01.938754Z","end":"2026-04-21T18:11:02.053448Z","steps":["trace[933029822] 'agreement among raft nodes before linearized reading' (duration: 114.51383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:02.053796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.267997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T18:11:02.053876Z","caller":"traceutil/trace.go:171","msg":"trace[1655359364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1272; }","duration":"417.40693ms","start":"2026-04-21T18:11:01.636451Z","end":"2026-04-21T18:11:02.053858Z","steps":["trace[1655359364] 'agreement among raft nodes before linearized reading' (duration: 416.864182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:02.053923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:01.636427Z","time spent":"417.486744ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T18:11:02.254175Z","caller":"traceutil/trace.go:171","msg":"trace[334215748] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"194.275475ms","start":"2026-04-21T18:11:02.059874Z","end":"2026-04-21T18:11:02.254149Z","steps":["trace[334215748] 'process raft request' (duration: 194.221458ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:02.254319Z","caller":"traceutil/trace.go:171","msg":"trace[458766154] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"194.38839ms","start":"2026-04-21T18:11:02.059818Z","end":"2026-04-21T18:11:02.254207Z","steps":["trace[458766154] 'process raft request' (duration: 190.129812ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:02.807753Z","caller":"traceutil/trace.go:171","msg":"trace[1969383328] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"493.393991ms","start":"2026-04-21T18:11:02.314316Z","end":"2026-04-21T18:11:02.80771Z","steps":["trace[1969383328] 'read index received' (duration: 439.277572ms)","trace[1969383328] 'applied index is now lower than readState.Index' (duration: 54.114919ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:11:02.808133Z","caller":"traceutil/trace.go:171","msg":"trace[438582812] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"547.508279ms","start":"2026-04-21T18:11:02.260586Z","end":"2026-04-21T18:11:02.808094Z","steps":["trace[438582812] 'process raft request' (duration: 493.158384ms)","trace[438582812] 'compare' (duration: 53.599829ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:11:02.808314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:02.260565Z","time spent":"547.65926ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:11:02.808361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.025914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-21T18:11:02.808411Z","caller":"traceutil/trace.go:171","msg":"trace[584247721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1276; }","duration":"494.156636ms","start":"2026-04-21T18:11:02.314238Z","end":"2026-04-21T18:11:02.808395Z","steps":["trace[584247721] 'agreement among raft nodes before linearized reading' (duration: 493.640227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:02.808453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:02.314189Z","time spent":"494.25446ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T18:11:03.028876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.915755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:11:03.028981Z","caller":"traceutil/trace.go:171","msg":"trace[1118299499] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"127.987969ms","start":"2026-04-21T18:11:02.900972Z","end":"2026-04-21T18:11:03.02896Z","steps":["trace[1118299499] 'read index received' (duration: 8.815349ms)","trace[1118299499] 'applied index is now lower than readState.Index' (duration: 119.17094ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:11:03.028998Z","caller":"traceutil/trace.go:171","msg":"trace[1215094198] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"214.082975ms","start":"2026-04-21T18:11:02.814893Z","end":"2026-04-21T18:11:03.028976Z","steps":["trace[1215094198] 'process raft request' (duration: 94.984851ms)","trace[1215094198] 'compare' (duration: 118.799192ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:11:03.029234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.226955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T18:11:03.029335Z","caller":"traceutil/trace.go:171","msg":"trace[113811431] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1277; }","duration":"128.404164ms","start":"2026-04-21T18:11:02.900907Z","end":"2026-04-21T18:11:03.029311Z","steps":["trace[113811431] 'agreement among raft nodes before linearized reading' (duration: 128.14563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:04.551295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.767894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:11:04.55143Z","caller":"traceutil/trace.go:171","msg":"trace[1205413580] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"287.401881ms","start":"2026-04-21T18:11:04.264005Z","end":"2026-04-21T18:11:04.551407Z","steps":["trace[1205413580] 'process raft request' (duration: 120.41923ms)","trace[1205413580] 'compare' (duration: 166.613933ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:11:05.921356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.70937ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-21T18:11:06.374956Z","caller":"traceutil/trace.go:171","msg":"trace[595906730] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"296.568061ms","start":"2026-04-21T18:11:06.07835Z","end":"2026-04-21T18:11:06.374918Z","steps":["trace[595906730] 'read index received' (duration: 296.21527ms)","trace[595906730] 'applied index is now lower than readState.Index' (duration: 350.861µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:11:06.375457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.061862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-21T18:11:06.37552Z","caller":"traceutil/trace.go:171","msg":"trace[38484435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1284; }","duration":"297.205214ms","start":"2026-04-21T18:11:06.0783Z","end":"2026-04-21T18:11:06.375505Z","steps":["trace[38484435] 'agreement among raft nodes before linearized reading' (duration: 296.808427ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:06.374983Z","caller":"traceutil/trace.go:171","msg":"trace[163703545] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"308.061898ms","start":"2026-04-21T18:11:06.066886Z","end":"2026-04-21T18:11:06.374948Z","steps":["trace[163703545] 'process raft request' (duration: 307.719747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:06.375847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:06.066847Z","time spent":"308.825714ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:11:06.808065Z","caller":"traceutil/trace.go:171","msg":"trace[798226375] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"426.37727ms","start":"2026-04-21T18:11:06.381657Z","end":"2026-04-21T18:11:06.808034Z","steps":["trace[798226375] 'process raft request' (duration: 389.578711ms)","trace[798226375] 'compare' (duration: 35.663724ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:11:06.808217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:06.381634Z","time spent":"426.503883ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:11:06.818679Z","caller":"traceutil/trace.go:171","msg":"trace[117818155] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1337; }","duration":"263.060631ms","start":"2026-04-21T18:11:06.555603Z","end":"2026-04-21T18:11:06.818663Z","steps":["trace[117818155] 'read index received' (duration: 215.70751ms)","trace[117818155] 'applied index is now lower than readState.Index' (duration: 47.352041ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:11:06.81873Z","caller":"traceutil/trace.go:171","msg":"trace[1207020871] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"436.910932ms","start":"2026-04-21T18:11:06.381799Z","end":"2026-04-21T18:11:06.81871Z","steps":["trace[1207020871] 'process raft request' (duration: 436.734323ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:06.818823Z","caller":"traceutil/trace.go:171","msg":"trace[1550304901] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"287.100556ms","start":"2026-04-21T18:11:06.531709Z","end":"2026-04-21T18:11:06.818809Z","steps":["trace[1550304901] 'process raft request' (duration: 286.910917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:06.818982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:06.381765Z","time spent":"437.009517ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:11:06.819233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.628858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-21T18:11:06.819287Z","caller":"traceutil/trace.go:171","msg":"trace[133817810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1287; }","duration":"263.726692ms","start":"2026-04-21T18:11:06.555548Z","end":"2026-04-21T18:11:06.819275Z","steps":["trace[133817810] 'agreement among raft nodes before linearized reading' (duration: 263.189944ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:08.57411Z","caller":"traceutil/trace.go:171","msg":"trace[42376104] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"191.21955ms","start":"2026-04-21T18:11:08.382869Z","end":"2026-04-21T18:11:08.574089Z","steps":["trace[42376104] 'process raft request' (duration: 191.087138ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:10.32197Z","caller":"traceutil/trace.go:171","msg":"trace[152103364] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1355; }","duration":"185.388965ms","start":"2026-04-21T18:11:10.136529Z","end":"2026-04-21T18:11:10.321918Z","steps":["trace[152103364] 'read index received' (duration: 102.88431ms)","trace[152103364] 'applied index is now lower than readState.Index' (duration: 82.503035ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:11:10.322101Z","caller":"traceutil/trace.go:171","msg":"trace[1030005929] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"187.611239ms","start":"2026-04-21T18:11:10.134432Z","end":"2026-04-21T18:11:10.322043Z","steps":["trace[1030005929] 'process raft request' (duration: 105.117054ms)","trace[1030005929] 'compare' (duration: 82.135095ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:11:10.3225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.804792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-27c69856-c5ca-4acb-b835-3e127ef43caa\" ","response":"range_response_count:1 size:1255"} {"level":"info","ts":"2026-04-21T18:11:10.322597Z","caller":"traceutil/trace.go:171","msg":"trace[1334082491] range","detail":"{range_begin:/registry/persistentvolumes/pvc-27c69856-c5ca-4acb-b835-3e127ef43caa; range_end:; response_count:1; response_revision:1302; }","duration":"186.063896ms","start":"2026-04-21T18:11:10.136484Z","end":"2026-04-21T18:11:10.322548Z","steps":["trace[1334082491] 'agreement among raft nodes before linearized reading' (duration: 185.618062ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:11.310228Z","caller":"traceutil/trace.go:171","msg":"trace[1956194046] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"350.406599ms","start":"2026-04-21T18:11:10.959799Z","end":"2026-04-21T18:11:11.310206Z","steps":["trace[1956194046] 'process raft request' (duration: 316.381208ms)","trace[1956194046] 'compare' (duration: 33.411255ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:11:11.310369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:10.959751Z","time spent":"350.547462ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:11:11.310711Z","caller":"traceutil/trace.go:171","msg":"trace[1377906274] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"234.047262ms","start":"2026-04-21T18:11:11.076Z","end":"2026-04-21T18:11:11.310047Z","steps":["trace[1377906274] 'read index received' (duration: 200.086577ms)","trace[1377906274] 'applied index is now lower than readState.Index' (duration: 33.959815ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:11:11.310907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.78137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-21T18:11:11.310999Z","caller":"traceutil/trace.go:171","msg":"trace[2099784890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1307; }","duration":"235.054365ms","start":"2026-04-21T18:11:11.075926Z","end":"2026-04-21T18:11:11.31098Z","steps":["trace[2099784890] 'agreement among raft nodes before linearized reading' (duration: 234.692486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:14.152608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.481183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2578"} {"level":"info","ts":"2026-04-21T18:11:14.152674Z","caller":"traceutil/trace.go:171","msg":"trace[1681985216] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1315; }","duration":"114.594226ms","start":"2026-04-21T18:11:14.038065Z","end":"2026-04-21T18:11:14.152659Z","steps":["trace[1681985216] 'range keys from in-memory index tree' (duration: 114.384649ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:14.204514Z","caller":"traceutil/trace.go:171","msg":"trace[2018333062] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"166.35025ms","start":"2026-04-21T18:11:14.038139Z","end":"2026-04-21T18:11:14.204489Z","steps":["trace[2018333062] 'process raft request' (duration: 166.17366ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:15.460028Z","caller":"traceutil/trace.go:171","msg":"trace[936167632] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"531.843787ms","start":"2026-04-21T18:11:14.928163Z","end":"2026-04-21T18:11:15.460007Z","steps":["trace[936167632] 'process raft request' (duration: 507.523482ms)","trace[936167632] 'compare' (duration: 24.077588ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:11:15.46002Z","caller":"traceutil/trace.go:171","msg":"trace[730572225] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1372; }","duration":"523.59915ms","start":"2026-04-21T18:11:14.936381Z","end":"2026-04-21T18:11:15.45998Z","steps":["trace[730572225] 'read index received' (duration: 499.311764ms)","trace[730572225] 'applied index is now lower than readState.Index' (duration: 24.284926ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:11:15.460298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:14.928147Z","time spent":"532.019478ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:11:15.484506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.347265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-21T18:11:15.484561Z","caller":"traceutil/trace.go:171","msg":"trace[1008599132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1319; }","duration":"472.454109ms","start":"2026-04-21T18:11:15.012094Z","end":"2026-04-21T18:11:15.484548Z","steps":["trace[1008599132] 'agreement among raft nodes before linearized reading' (duration: 472.293198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:15.484591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:15.012074Z","time spent":"472.512106ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T18:11:15.484734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.007299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T18:11:15.484758Z","caller":"traceutil/trace.go:171","msg":"trace[113307705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1319; }","duration":"149.064746ms","start":"2026-04-21T18:11:15.335687Z","end":"2026-04-21T18:11:15.484752Z","steps":["trace[113307705] 'agreement among raft nodes before linearized reading' (duration: 148.999358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:15.484887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.486333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:11:15.484971Z","caller":"traceutil/trace.go:171","msg":"trace[1025236855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1319; }","duration":"548.607407ms","start":"2026-04-21T18:11:14.936345Z","end":"2026-04-21T18:11:15.484952Z","steps":["trace[1025236855] 'agreement among raft nodes before linearized reading' (duration: 523.721263ms)","trace[1025236855] 'range keys from in-memory index tree' (duration: 24.757701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:11:15.48501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:14.936334Z","time spent":"548.663043ms","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T18:11:16.287027Z","caller":"traceutil/trace.go:171","msg":"trace[621768974] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"198.303285ms","start":"2026-04-21T18:11:16.088693Z","end":"2026-04-21T18:11:16.286996Z","steps":["trace[621768974] 'process raft request' (duration: 198.105235ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:16.321916Z","caller":"traceutil/trace.go:171","msg":"trace[647195877] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"112.274616ms","start":"2026-04-21T18:11:16.209612Z","end":"2026-04-21T18:11:16.321886Z","steps":["trace[647195877] 'process raft request' (duration: 111.909966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:17.344085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.681088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:11:17.344371Z","caller":"traceutil/trace.go:171","msg":"trace[79513088] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"208.147028ms","start":"2026-04-21T18:11:17.136195Z","end":"2026-04-21T18:11:17.344342Z","steps":["trace[79513088] 'process raft request' (duration: 208.026885ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:17.344667Z","caller":"traceutil/trace.go:171","msg":"trace[2107285270] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"315.03157ms","start":"2026-04-21T18:11:17.02958Z","end":"2026-04-21T18:11:17.344611Z","steps":["trace[2107285270] 'process raft request' (duration: 66.723396ms)","trace[2107285270] 'compare' (duration: 247.300308ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:11:17.345328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:11:17.029558Z","time spent":"315.693084ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:11:21.267342Z","caller":"traceutil/trace.go:171","msg":"trace[802927069] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"134.978113ms","start":"2026-04-21T18:11:21.132328Z","end":"2026-04-21T18:11:21.267306Z","steps":["trace[802927069] 'process raft request' (duration: 134.781553ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:22.895625Z","caller":"traceutil/trace.go:171","msg":"trace[777856344] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1405; }","duration":"147.982752ms","start":"2026-04-21T18:11:22.747617Z","end":"2026-04-21T18:11:22.895599Z","steps":["trace[777856344] 'read index received' (duration: 147.763814ms)","trace[777856344] 'applied index is now lower than readState.Index' (duration: 218.008µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:11:22.895671Z","caller":"traceutil/trace.go:171","msg":"trace[1941648126] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"167.921277ms","start":"2026-04-21T18:11:22.727726Z","end":"2026-04-21T18:11:22.895647Z","steps":["trace[1941648126] 'process raft request' (duration: 167.712028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:22.895887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.256187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/local-path-provisioner\" ","response":"range_response_count:1 size:1106"} {"level":"warn","ts":"2026-04-21T18:11:22.895899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.615515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kubeadm:get-nodes\" ","response":"range_response_count:1 size:305"} {"level":"info","ts":"2026-04-21T18:11:22.895933Z","caller":"traceutil/trace.go:171","msg":"trace[260406739] range","detail":"{range_begin:/registry/clusterroles/local-path-provisioner; range_end:; response_count:1; response_revision:1349; }","duration":"148.368311ms","start":"2026-04-21T18:11:22.747552Z","end":"2026-04-21T18:11:22.89592Z","steps":["trace[260406739] 'agreement among raft nodes before linearized reading' (duration: 148.179351ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:22.895939Z","caller":"traceutil/trace.go:171","msg":"trace[237395832] range","detail":"{range_begin:/registry/clusterroles/kubeadm:get-nodes; range_end:; response_count:1; response_revision:1349; }","duration":"103.69787ms","start":"2026-04-21T18:11:22.792227Z","end":"2026-04-21T18:11:22.895925Z","steps":["trace[237395832] 'agreement among raft nodes before linearized reading' (duration: 103.623584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:23.092894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.71296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:1 size:840"} {"level":"info","ts":"2026-04-21T18:11:23.092956Z","caller":"traceutil/trace.go:171","msg":"trace[1478191568] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:1; response_revision:1349; }","duration":"101.795826ms","start":"2026-04-21T18:11:22.991147Z","end":"2026-04-21T18:11:23.092943Z","steps":["trace[1478191568] 'range keys from in-memory index tree' (duration: 101.615596ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:25.018993Z","caller":"traceutil/trace.go:171","msg":"trace[1498194840] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"118.509667ms","start":"2026-04-21T18:11:24.900457Z","end":"2026-04-21T18:11:25.018967Z","steps":["trace[1498194840] 'process raft request' (duration: 118.311866ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:25.747518Z","caller":"traceutil/trace.go:171","msg":"trace[267299814] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"110.924055ms","start":"2026-04-21T18:11:25.636572Z","end":"2026-04-21T18:11:25.747496Z","steps":["trace[267299814] 'process raft request' (duration: 110.779522ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:25.747683Z","caller":"traceutil/trace.go:171","msg":"trace[127679912] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"111.003281ms","start":"2026-04-21T18:11:25.636664Z","end":"2026-04-21T18:11:25.747668Z","steps":["trace[127679912] 'process raft request' (duration: 110.799011ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:35.50731Z","caller":"traceutil/trace.go:171","msg":"trace[1685054244] linearizableReadLoop","detail":"{readStateIndex:1449; appliedIndex:1448; }","duration":"136.424361ms","start":"2026-04-21T18:11:35.370868Z","end":"2026-04-21T18:11:35.507293Z","steps":["trace[1685054244] 'read index received' (duration: 136.290607ms)","trace[1685054244] 'applied index is now lower than readState.Index' (duration: 133.034µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:11:35.507684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.803861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding\" ","response":"range_response_count:1 size:1146"} {"level":"info","ts":"2026-04-21T18:11:35.507449Z","caller":"traceutil/trace.go:171","msg":"trace[1617439829] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"141.52804ms","start":"2026-04-21T18:11:35.365847Z","end":"2026-04-21T18:11:35.507375Z","steps":["trace[1617439829] 'process raft request' (duration: 141.32911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:35.507796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.981944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1111"} {"level":"info","ts":"2026-04-21T18:11:35.507822Z","caller":"traceutil/trace.go:171","msg":"trace[735159061] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:1389; }","duration":"109.037982ms","start":"2026-04-21T18:11:35.398777Z","end":"2026-04-21T18:11:35.507815Z","steps":["trace[735159061] 'agreement among raft nodes before linearized reading' (duration: 108.958016ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:35.507713Z","caller":"traceutil/trace.go:171","msg":"trace[391937987] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding; range_end:; response_count:1; response_revision:1389; }","duration":"136.846239ms","start":"2026-04-21T18:11:35.370858Z","end":"2026-04-21T18:11:35.507704Z","steps":["trace[391937987] 'agreement among raft nodes before linearized reading' (duration: 136.537794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:36.12437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.603741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/\" range_end:\"/registry/configmaps/kube-public0\" limit:500 ","response":"range_response_count:2 size:3917"} {"level":"info","ts":"2026-04-21T18:11:36.12444Z","caller":"traceutil/trace.go:171","msg":"trace[183505833] range","detail":"{range_begin:/registry/configmaps/kube-public/; range_end:/registry/configmaps/kube-public0; response_count:2; response_revision:1393; }","duration":"119.725336ms","start":"2026-04-21T18:11:36.004697Z","end":"2026-04-21T18:11:36.124422Z","steps":["trace[183505833] 'range keys from in-memory index tree' (duration: 119.481328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:36.124639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.411094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-04-21T18:11:36.124664Z","caller":"traceutil/trace.go:171","msg":"trace[1860436822] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1393; }","duration":"143.464763ms","start":"2026-04-21T18:11:35.981192Z","end":"2026-04-21T18:11:36.124657Z","steps":["trace[1860436822] 'range keys from in-memory index tree' (duration: 143.338478ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:38.090235Z","caller":"traceutil/trace.go:171","msg":"trace[85045368] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1459; }","duration":"127.116704ms","start":"2026-04-21T18:11:37.963089Z","end":"2026-04-21T18:11:38.090206Z","steps":["trace[85045368] 'read index received' (duration: 31.238485ms)","trace[85045368] 'applied index is now lower than readState.Index' (duration: 95.876819ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:11:38.090277Z","caller":"traceutil/trace.go:171","msg":"trace[1844817597] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"127.680637ms","start":"2026-04-21T18:11:37.962574Z","end":"2026-04-21T18:11:38.090255Z","steps":["trace[1844817597] 'process raft request' (duration: 124.463952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:38.090821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.728104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:70 size:51927"} {"level":"info","ts":"2026-04-21T18:11:38.090858Z","caller":"traceutil/trace.go:171","msg":"trace[1142016128] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:70; response_revision:1401; }","duration":"127.783182ms","start":"2026-04-21T18:11:37.963062Z","end":"2026-04-21T18:11:38.090845Z","steps":["trace[1142016128] 'agreement among raft nodes before linearized reading' (duration: 127.314535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:38.422091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.081464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-proxy\" ","response":"range_response_count:1 size:1949"} {"level":"warn","ts":"2026-04-21T18:11:38.422118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.399674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-04-21T18:11:38.422195Z","caller":"traceutil/trace.go:171","msg":"trace[555656806] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1401; }","duration":"167.255166ms","start":"2026-04-21T18:11:38.254913Z","end":"2026-04-21T18:11:38.422168Z","steps":["trace[555656806] 'range keys from in-memory index tree' (duration: 166.948291ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:38.422199Z","caller":"traceutil/trace.go:171","msg":"trace[1542012797] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:1401; }","duration":"228.495389ms","start":"2026-04-21T18:11:38.193682Z","end":"2026-04-21T18:11:38.422177Z","steps":["trace[1542012797] 'range keys from in-memory index tree' (duration: 228.2654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:38.422313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.59622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:11:38.422398Z","caller":"traceutil/trace.go:171","msg":"trace[1439044341] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:1401; }","duration":"162.693216ms","start":"2026-04-21T18:11:38.259682Z","end":"2026-04-21T18:11:38.422375Z","steps":["trace[1439044341] 'range keys from in-memory index tree' (duration: 162.547103ms)"],"step_count":1}