{"level":"warn","ts":"2026-04-13T17:12:48.738426Z","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-13T17:12:48.738585Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-13T17:12:48.740003Z","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-13T17:12:48.740038Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-13T17:12:48.740067Z","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-13T17:12:48.741257Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-04-13T17:12:48.741427Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-13T17:12:48.755042Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.999751ms"} {"level":"info","ts":"2026-04-13T17:12:48.762659Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-04-13T17:12:48.762725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T17:12:48.762745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-04-13T17:12:48.762795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T17:12:48.762811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-04-13T17:12:48.762876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-04-13T17:12:48.775797Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T17:12:48.777364Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T17:12:48.778572Z","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-13T17:12:48.783329Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T17:12:48.783606Z","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-13T17:12:48.783709Z","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-13T17:12:48.783746Z","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-13T17:12:48.784153Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T17:12:48.784969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-04-13T17:12:48.786005Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-13T17:12:48.790556Z","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-13T17:12:48.790676Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-13T17:12:48.790724Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-13T17:12:48.790868Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T17:12:48.790901Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T17:12:49.663358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T17:12:49.663445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T17:12:49.663495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-04-13T17:12:49.663516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-04-13T17:12:49.663526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-13T17:12:49.663542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-04-13T17:12:49.663555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-13T17:12:49.664574Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:12:49.665603Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:12:49.665597Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T17:12:49.66564Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:12:49.666025Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T17:12:49.666066Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T17:12:49.66818Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:12:49.668289Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:12:49.668323Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:12:49.672124Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-04-13T17:12:49.672526Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-13T17:13:03.737685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.155236ms","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-13T17:13:03.737778Z","caller":"traceutil/trace.go:171","msg":"trace[569190979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:359; }","duration":"316.32787ms","start":"2026-04-13T17:13:03.421431Z","end":"2026-04-13T17:13:03.737759Z","steps":["trace[569190979] 'range keys from in-memory index tree' (duration: 316.032315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:03.737969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:03.421404Z","time spent":"316.415472ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T17:13:09.646063Z","caller":"traceutil/trace.go:171","msg":"trace[1177441068] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"390.629635ms","start":"2026-04-13T17:13:09.255411Z","end":"2026-04-13T17:13:09.646041Z","steps":["trace[1177441068] 'process raft request' (duration: 390.522171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:09.646668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:09.255393Z","time spent":"390.758718ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:13:09.928388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.192372ms","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-13T17:13:09.928497Z","caller":"traceutil/trace.go:171","msg":"trace[1718095054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:407; }","duration":"140.351316ms","start":"2026-04-13T17:13:09.788117Z","end":"2026-04-13T17:13:09.928468Z","steps":["trace[1718095054] 'range keys from in-memory index tree' (duration: 140.088361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:09.928393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.897509ms","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-13T17:13:09.92872Z","caller":"traceutil/trace.go:171","msg":"trace[394082954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"161.270277ms","start":"2026-04-13T17:13:09.767418Z","end":"2026-04-13T17:13:09.928688Z","steps":["trace[394082954] 'range keys from in-memory index tree' (duration: 160.796778ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:15.542121Z","caller":"traceutil/trace.go:171","msg":"trace[36234533] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"524.623463ms","start":"2026-04-13T17:13:15.017467Z","end":"2026-04-13T17:13:15.542091Z","steps":["trace[36234533] 'process raft request' (duration: 524.51263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:15.542273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:15.017441Z","time spent":"524.753706ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:13:15.948146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.782833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T17:13:15.948204Z","caller":"traceutil/trace.go:171","msg":"trace[746317335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"282.848345ms","start":"2026-04-13T17:13:15.665344Z","end":"2026-04-13T17:13:15.948192Z","steps":["trace[746317335] 'range keys from in-memory index tree' (duration: 282.697352ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:20.109015Z","caller":"traceutil/trace.go:171","msg":"trace[1317110447] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"135.707652ms","start":"2026-04-13T17:13:19.973276Z","end":"2026-04-13T17:13:20.108983Z","steps":["trace[1317110447] 'process raft request' (duration: 135.545699ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:20.109555Z","caller":"traceutil/trace.go:171","msg":"trace[1301542142] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:478; }","duration":"133.895507ms","start":"2026-04-13T17:13:19.975635Z","end":"2026-04-13T17:13:20.109531Z","steps":["trace[1301542142] 'read index received' (duration: 133.836026ms)","trace[1301542142] 'applied index is now lower than readState.Index' (duration: 58.221µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:13:20.109725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.07636ms","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-13T17:13:20.109767Z","caller":"traceutil/trace.go:171","msg":"trace[1952840299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"134.133482ms","start":"2026-04-13T17:13:19.975623Z","end":"2026-04-13T17:13:20.109757Z","steps":["trace[1952840299] 'agreement among raft nodes before linearized reading' (duration: 134.04155ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:20.162609Z","caller":"traceutil/trace.go:171","msg":"trace[769892502] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"107.949465ms","start":"2026-04-13T17:13:20.054613Z","end":"2026-04-13T17:13:20.162562Z","steps":["trace[769892502] 'process raft request' (duration: 107.705059ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:20.162687Z","caller":"traceutil/trace.go:171","msg":"trace[2011839452] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"101.685628ms","start":"2026-04-13T17:13:20.060993Z","end":"2026-04-13T17:13:20.162679Z","steps":["trace[2011839452] 'process raft request' (duration: 101.509604ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:20.162612Z","caller":"traceutil/trace.go:171","msg":"trace[657047450] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"101.674847ms","start":"2026-04-13T17:13:20.060902Z","end":"2026-04-13T17:13:20.162577Z","steps":["trace[657047450] 'process raft request' (duration: 101.531933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:20.163304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.25606ms","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-13T17:13:20.1634Z","caller":"traceutil/trace.go:171","msg":"trace[1896796306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:475; }","duration":"185.393663ms","start":"2026-04-13T17:13:19.977993Z","end":"2026-04-13T17:13:20.163387Z","steps":["trace[1896796306] 'agreement among raft nodes before linearized reading' (duration: 185.230429ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:20.650389Z","caller":"traceutil/trace.go:171","msg":"trace[42622686] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"308.016322ms","start":"2026-04-13T17:13:20.342337Z","end":"2026-04-13T17:13:20.650353Z","steps":["trace[42622686] 'process raft request' (duration: 300.573482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:20.651146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:20.342312Z","time spent":"308.202385ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:13:20.653487Z","caller":"traceutil/trace.go:171","msg":"trace[2002171630] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"302.924208ms","start":"2026-04-13T17:13:20.350539Z","end":"2026-04-13T17:13:20.653463Z","steps":["trace[2002171630] 'process raft request' (duration: 302.719824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:20.653589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:20.350523Z","time spent":"303.016081ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:13:20.653629Z","caller":"traceutil/trace.go:171","msg":"trace[1564681608] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"259.194548ms","start":"2026-04-13T17:13:20.394427Z","end":"2026-04-13T17:13:20.653622Z","steps":["trace[1564681608] 'process raft request' (duration: 259.015965ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:20.653572Z","caller":"traceutil/trace.go:171","msg":"trace[1934034500] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"282.488918ms","start":"2026-04-13T17:13:20.371051Z","end":"2026-04-13T17:13:20.65354Z","steps":["trace[1934034500] 'process raft request' (duration: 282.331865ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:20.994066Z","caller":"traceutil/trace.go:171","msg":"trace[909034870] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"114.313422ms","start":"2026-04-13T17:13:20.879715Z","end":"2026-04-13T17:13:20.994029Z","steps":["trace[909034870] 'read index received' (duration: 61.988778ms)","trace[909034870] 'applied index is now lower than readState.Index' (duration: 52.323223ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:13:20.994111Z","caller":"traceutil/trace.go:171","msg":"trace[1327088656] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"148.683164ms","start":"2026-04-13T17:13:20.845399Z","end":"2026-04-13T17:13:20.994083Z","steps":["trace[1327088656] 'process raft request' (duration: 96.32907ms)","trace[1327088656] 'compare' (duration: 51.995357ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:13:20.994894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.151509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:20.995032Z","caller":"traceutil/trace.go:171","msg":"trace[1129839511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"115.320232ms","start":"2026-04-13T17:13:20.879662Z","end":"2026-04-13T17:13:20.994983Z","steps":["trace[1129839511] 'agreement among raft nodes before linearized reading' (duration: 114.489426ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:20.999999Z","caller":"traceutil/trace.go:171","msg":"trace[482080396] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"104.515365ms","start":"2026-04-13T17:13:20.895455Z","end":"2026-04-13T17:13:20.999971Z","steps":["trace[482080396] 'process raft request' (duration: 104.227468ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:25.955867Z","caller":"traceutil/trace.go:171","msg":"trace[1964614401] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"196.411255ms","start":"2026-04-13T17:13:25.759432Z","end":"2026-04-13T17:13:25.955843Z","steps":["trace[1964614401] 'process raft request' (duration: 190.280172ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:26.814636Z","caller":"traceutil/trace.go:171","msg":"trace[1333081074] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"126.745972ms","start":"2026-04-13T17:13:26.687861Z","end":"2026-04-13T17:13:26.814607Z","steps":["trace[1333081074] 'process raft request' (duration: 126.563258ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:27.299733Z","caller":"traceutil/trace.go:171","msg":"trace[2051374702] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"431.850205ms","start":"2026-04-13T17:13:26.867854Z","end":"2026-04-13T17:13:27.299704Z","steps":["trace[2051374702] 'process raft request' (duration: 430.500827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:27.299884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:26.867823Z","time spent":"431.983848ms","remote":"127.0.0.1:41254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:13:27.909385Z","caller":"traceutil/trace.go:171","msg":"trace[1193906861] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"353.482747ms","start":"2026-04-13T17:13:27.555869Z","end":"2026-04-13T17:13:27.909352Z","steps":["trace[1193906861] 'read index received' (duration: 353.339064ms)","trace[1193906861] 'applied index is now lower than readState.Index' (duration: 142.343µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:13:27.909504Z","caller":"traceutil/trace.go:171","msg":"trace[293462588] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"554.28404ms","start":"2026-04-13T17:13:27.355178Z","end":"2026-04-13T17:13:27.909462Z","steps":["trace[293462588] 'process raft request' (duration: 553.977183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:27.909616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:27.355154Z","time spent":"554.411013ms","remote":"127.0.0.1:41706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:13:27.909802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.850175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q795s\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-13T17:13:27.909869Z","caller":"traceutil/trace.go:171","msg":"trace[1231613541] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q795s; range_end:; response_count:1; response_revision:576; }","duration":"354.019659ms","start":"2026-04-13T17:13:27.55583Z","end":"2026-04-13T17:13:27.90985Z","steps":["trace[1231613541] 'agreement among raft nodes before linearized reading' (duration: 353.648502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:27.91006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:27.555814Z","time spent":"354.170951ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-q795s\" "} {"level":"warn","ts":"2026-04-13T17:13:29.750701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.833697171s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-13T17:13:29.750899Z","caller":"traceutil/trace.go:171","msg":"trace[776526913] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"1.841414237s","start":"2026-04-13T17:13:27.909457Z","end":"2026-04-13T17:13:29.750871Z","steps":["trace[776526913] 'read index received' (duration: 8.197234ms)","trace[776526913] 'applied index is now lower than readState.Index' (duration: 1.833214903s)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:13:29.75114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.830381814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:13:29.751203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.924245ms","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-13T17:13:29.751239Z","caller":"traceutil/trace.go:171","msg":"trace[263963861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"932.009116ms","start":"2026-04-13T17:13:28.819219Z","end":"2026-04-13T17:13:29.751229Z","steps":["trace[263963861] 'agreement among raft nodes before linearized reading' (duration: 931.866933ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:29.751256Z","caller":"traceutil/trace.go:171","msg":"trace[387584131] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:577; }","duration":"1.830545728s","start":"2026-04-13T17:13:27.920678Z","end":"2026-04-13T17:13:29.751224Z","steps":["trace[387584131] 'agreement among raft nodes before linearized reading' (duration: 1.830361664s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:29.751275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:28.819192Z","time spent":"932.075277ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T17:13:29.751315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:27.920662Z","time spent":"1.830637129s","remote":"127.0.0.1:41402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-13T17:13:29.751449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.239931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:13:29.751427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.098768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:29.751485Z","caller":"traceutil/trace.go:171","msg":"trace[877985028] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:577; }","duration":"117.311282ms","start":"2026-04-13T17:13:29.634164Z","end":"2026-04-13T17:13:29.751476Z","steps":["trace[877985028] 'agreement among raft nodes before linearized reading' (duration: 117.257851ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:29.751521Z","caller":"traceutil/trace.go:171","msg":"trace[900142542] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:577; }","duration":"117.21284ms","start":"2026-04-13T17:13:29.634285Z","end":"2026-04-13T17:13:29.751498Z","steps":["trace[900142542] 'agreement among raft nodes before linearized reading' (duration: 117.051617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:29.751684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537016797s","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-13T17:13:29.751745Z","caller":"traceutil/trace.go:171","msg":"trace[869960933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"1.53711844s","start":"2026-04-13T17:13:28.214611Z","end":"2026-04-13T17:13:29.751729Z","steps":["trace[869960933] 'agreement among raft nodes before linearized reading' (duration: 1.536923767s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:29.752023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.610933697s","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-13T17:13:29.752084Z","caller":"traceutil/trace.go:171","msg":"trace[91562583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"1.611054419s","start":"2026-04-13T17:13:28.141017Z","end":"2026-04-13T17:13:29.752071Z","steps":["trace[91562583] 'agreement among raft nodes before linearized reading' (duration: 1.610941166s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:29.752137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:28.140986Z","time spent":"1.611142211s","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T17:13:29.75231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.557796586s","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-13T17:13:29.752401Z","caller":"traceutil/trace.go:171","msg":"trace[945700800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"1.55798782s","start":"2026-04-13T17:13:28.194386Z","end":"2026-04-13T17:13:29.752374Z","steps":["trace[945700800] 'agreement among raft nodes before linearized reading' (duration: 1.557823877s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:29.752479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:28.194355Z","time spent":"1.558106393s","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T17:13:29.753539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:28.214547Z","time spent":"1.537233442s","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T17:13:29.753826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.876115836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:29.753863Z","caller":"traceutil/trace.go:171","msg":"trace[249929075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"1.876183628s","start":"2026-04-13T17:13:27.877667Z","end":"2026-04-13T17:13:29.753851Z","steps":["trace[249929075] 'agreement among raft nodes before linearized reading' (duration: 1.873270329s)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:29.750979Z","caller":"traceutil/trace.go:171","msg":"trace[199449417] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.827401535s","start":"2026-04-13T17:13:27.923568Z","end":"2026-04-13T17:13:29.750969Z","steps":["trace[199449417] 'process raft request' (duration: 1.827235941s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:29.753906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:27.877647Z","time spent":"1.876252159s","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:13:29.753993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:27.923539Z","time spent":"1.830384055s","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:13:31.001316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.652915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:31.001387Z","caller":"traceutil/trace.go:171","msg":"trace[2004265994] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:592; }","duration":"230.753526ms","start":"2026-04-13T17:13:30.770619Z","end":"2026-04-13T17:13:31.001373Z","steps":["trace[2004265994] 'count revisions from in-memory index tree' (duration: 230.601044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:31.001472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.758107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:31.00156Z","caller":"traceutil/trace.go:171","msg":"trace[257541343] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:592; }","duration":"230.893239ms","start":"2026-04-13T17:13:30.770656Z","end":"2026-04-13T17:13:31.001549Z","steps":["trace[257541343] 'range keys from in-memory index tree' (duration: 230.725605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:31.001571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.580278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:13:31.001634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.723127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:31.001679Z","caller":"traceutil/trace.go:171","msg":"trace[2079580499] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:592; }","duration":"245.81946ms","start":"2026-04-13T17:13:30.75585Z","end":"2026-04-13T17:13:31.001669Z","steps":["trace[2079580499] 'count revisions from in-memory index tree' (duration: 245.640635ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:31.00167Z","caller":"traceutil/trace.go:171","msg":"trace[277493564] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:592; }","duration":"267.707891ms","start":"2026-04-13T17:13:30.733938Z","end":"2026-04-13T17:13:31.001646Z","steps":["trace[277493564] 'range keys from in-memory index tree' (duration: 267.537967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:31.001679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.587768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:13:31.001703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.179542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:13:31.001739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.567402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:31.001746Z","caller":"traceutil/trace.go:171","msg":"trace[1563244852] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:592; }","duration":"284.245873ms","start":"2026-04-13T17:13:30.71748Z","end":"2026-04-13T17:13:31.001726Z","steps":["trace[1563244852] 'count revisions from in-memory index tree' (duration: 284.047079ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:31.001739Z","caller":"traceutil/trace.go:171","msg":"trace[800116081] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:592; }","duration":"267.671709ms","start":"2026-04-13T17:13:30.734048Z","end":"2026-04-13T17:13:31.00172Z","steps":["trace[800116081] 'count revisions from in-memory index tree' (duration: 267.505226ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:31.001765Z","caller":"traceutil/trace.go:171","msg":"trace[1591763654] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:592; }","duration":"230.606704ms","start":"2026-04-13T17:13:30.771151Z","end":"2026-04-13T17:13:31.001757Z","steps":["trace[1591763654] 'range keys from in-memory index tree' (duration: 230.538762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:31.001753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.147701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:13:31.001613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.935435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:31.001818Z","caller":"traceutil/trace.go:171","msg":"trace[745837394] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:592; }","duration":"284.232883ms","start":"2026-04-13T17:13:30.717569Z","end":"2026-04-13T17:13:31.001802Z","steps":["trace[745837394] 'range keys from in-memory index tree' (duration: 284.1004ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:31.001829Z","caller":"traceutil/trace.go:171","msg":"trace[1725839312] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:592; }","duration":"267.18434ms","start":"2026-04-13T17:13:30.734632Z","end":"2026-04-13T17:13:31.001817Z","steps":["trace[1725839312] 'range keys from in-memory index tree' (duration: 266.735982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:31.001867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.776554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:13:31.001875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.733442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:13:31.001914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.776074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:31.001922Z","caller":"traceutil/trace.go:171","msg":"trace[939643933] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:592; }","duration":"283.849086ms","start":"2026-04-13T17:13:30.718056Z","end":"2026-04-13T17:13:31.001905Z","steps":["trace[939643933] 'range keys from in-memory index tree' (duration: 283.686252ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:31.001932Z","caller":"traceutil/trace.go:171","msg":"trace[1264407289] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:592; }","duration":"214.825825ms","start":"2026-04-13T17:13:30.787101Z","end":"2026-04-13T17:13:31.001926Z","steps":["trace[1264407289] 'range keys from in-memory index tree' (duration: 214.728874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:31.001956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.996583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:31.00189Z","caller":"traceutil/trace.go:171","msg":"trace[1676885191] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:592; }","duration":"214.814145ms","start":"2026-04-13T17:13:30.787069Z","end":"2026-04-13T17:13:31.001883Z","steps":["trace[1676885191] 'count revisions from in-memory index tree' (duration: 214.747334ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:31.001996Z","caller":"traceutil/trace.go:171","msg":"trace[435194932] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:592; }","duration":"246.035903ms","start":"2026-04-13T17:13:30.755946Z","end":"2026-04-13T17:13:31.001982Z","steps":["trace[435194932] 'range keys from in-memory index tree' (duration: 245.942771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:31.002055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.716614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:31.00208Z","caller":"traceutil/trace.go:171","msg":"trace[485375703] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:592; }","duration":"214.758174ms","start":"2026-04-13T17:13:30.787315Z","end":"2026-04-13T17:13:31.002074Z","steps":["trace[485375703] 'range keys from in-memory index tree' (duration: 214.684933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:31.002149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.984106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:31.002173Z","caller":"traceutil/trace.go:171","msg":"trace[792993039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"124.035317ms","start":"2026-04-13T17:13:30.878131Z","end":"2026-04-13T17:13:31.002166Z","steps":["trace[792993039] 'range keys from in-memory index tree' (duration: 123.939005ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:46.166123Z","caller":"traceutil/trace.go:171","msg":"trace[679887833] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"287.364474ms","start":"2026-04-13T17:13:45.878732Z","end":"2026-04-13T17:13:46.166096Z","steps":["trace[679887833] 'read index received' (duration: 287.053248ms)","trace[679887833] 'applied index is now lower than readState.Index' (duration: 309.906µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:13:46.166173Z","caller":"traceutil/trace.go:171","msg":"trace[1036951622] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"489.431614ms","start":"2026-04-13T17:13:45.676712Z","end":"2026-04-13T17:13:46.166144Z","steps":["trace[1036951622] 'process raft request' (duration: 489.20557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:46.166339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:45.676687Z","time spent":"489.561296ms","remote":"127.0.0.1:41402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:13:46.16639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.807588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T17:13:46.166427Z","caller":"traceutil/trace.go:171","msg":"trace[1316504542] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:744; }","duration":"265.9072ms","start":"2026-04-13T17:13:45.900509Z","end":"2026-04-13T17:13:46.166417Z","steps":["trace[1316504542] 'agreement among raft nodes before linearized reading' (duration: 265.752217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:46.166408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.591339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:46.166519Z","caller":"traceutil/trace.go:171","msg":"trace[479857392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"287.837524ms","start":"2026-04-13T17:13:45.878664Z","end":"2026-04-13T17:13:46.166501Z","steps":["trace[479857392] 'agreement among raft nodes before linearized reading' (duration: 287.593439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:46.166525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.390773ms","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-13T17:13:46.166589Z","caller":"traceutil/trace.go:171","msg":"trace[1059335930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:744; }","duration":"256.484854ms","start":"2026-04-13T17:13:45.910089Z","end":"2026-04-13T17:13:46.166574Z","steps":["trace[1059335930] 'agreement among raft nodes before linearized reading' (duration: 256.27001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:46.166743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.251108ms","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-13T17:13:46.166763Z","caller":"traceutil/trace.go:171","msg":"trace[1969106546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:744; }","duration":"262.317309ms","start":"2026-04-13T17:13:45.90444Z","end":"2026-04-13T17:13:46.166757Z","steps":["trace[1969106546] 'agreement among raft nodes before linearized reading' (duration: 262.244507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:46.166868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.259298ms","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-13T17:13:46.166908Z","caller":"traceutil/trace.go:171","msg":"trace[260484629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:744; }","duration":"261.333829ms","start":"2026-04-13T17:13:45.905563Z","end":"2026-04-13T17:13:46.166896Z","steps":["trace[260484629] 'agreement among raft nodes before linearized reading' (duration: 261.192307ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:20.641395Z","caller":"traceutil/trace.go:171","msg":"trace[525284869] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"207.267012ms","start":"2026-04-13T17:14:20.434103Z","end":"2026-04-13T17:14:20.64137Z","steps":["trace[525284869] 'read index received' (duration: 207.039767ms)","trace[525284869] 'applied index is now lower than readState.Index' (duration: 226.155µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:20.641437Z","caller":"traceutil/trace.go:171","msg":"trace[863266430] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"305.556043ms","start":"2026-04-13T17:14:20.33586Z","end":"2026-04-13T17:14:20.641416Z","steps":["trace[863266430] 'process raft request' (duration: 305.379299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:20.641564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:20.335839Z","time spent":"305.660696ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:14:20.64161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.494487ms","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-13T17:14:20.64167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.275638ms","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-13T17:14:20.641673Z","caller":"traceutil/trace.go:171","msg":"trace[1480329502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:956; }","duration":"207.589679ms","start":"2026-04-13T17:14:20.434066Z","end":"2026-04-13T17:14:20.641656Z","steps":["trace[1480329502] 'agreement among raft nodes before linearized reading' (duration: 207.397945ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:20.641706Z","caller":"traceutil/trace.go:171","msg":"trace[1335582423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:956; }","duration":"149.3671ms","start":"2026-04-13T17:14:20.492329Z","end":"2026-04-13T17:14:20.641696Z","steps":["trace[1335582423] 'agreement among raft nodes before linearized reading' (duration: 149.256747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:20.641829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.791728ms","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-13T17:14:20.641915Z","caller":"traceutil/trace.go:171","msg":"trace[1412032883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:956; }","duration":"149.849178ms","start":"2026-04-13T17:14:20.492001Z","end":"2026-04-13T17:14:20.641851Z","steps":["trace[1412032883] 'agreement among raft nodes before linearized reading' (duration: 149.493942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:21.114376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.866207ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:14:21.114575Z","caller":"traceutil/trace.go:171","msg":"trace[1598169659] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:956; }","duration":"197.093592ms","start":"2026-04-13T17:14:20.917457Z","end":"2026-04-13T17:14:21.114551Z","steps":["trace[1598169659] 'range keys from in-memory index tree' (duration: 196.842946ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:21.489827Z","caller":"traceutil/trace.go:171","msg":"trace[1876154620] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"844.753139ms","start":"2026-04-13T17:14:20.645054Z","end":"2026-04-13T17:14:21.489807Z","steps":["trace[1876154620] 'process raft request' (duration: 844.428842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:21.489937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:20.645038Z","time spent":"844.846031ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:14:21.95719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.836951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:14:21.957353Z","caller":"traceutil/trace.go:171","msg":"trace[1017273694] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:987; }","duration":"823.030009ms","start":"2026-04-13T17:14:21.134308Z","end":"2026-04-13T17:14:21.957338Z","steps":["trace[1017273694] 'read index received' (duration: 355.507289ms)","trace[1017273694] 'applied index is now lower than readState.Index' (duration: 467.5215ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:21.957436Z","caller":"traceutil/trace.go:171","msg":"trace[712257551] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"1.312037666s","start":"2026-04-13T17:14:20.645385Z","end":"2026-04-13T17:14:21.957423Z","steps":["trace[712257551] 'process raft request' (duration: 898.896128ms)","trace[712257551] 'compare' (duration: 412.746309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:14:21.957495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:20.645362Z","time spent":"1.312099238s","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:14:21.957514Z","caller":"traceutil/trace.go:171","msg":"trace[945576950] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"1.089616794s","start":"2026-04-13T17:14:20.867883Z","end":"2026-04-13T17:14:21.9575Z","steps":["trace[945576950] 'process raft request' (duration: 1.089415109s)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:21.957504Z","caller":"traceutil/trace.go:171","msg":"trace[1760391721] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"1.312018985s","start":"2026-04-13T17:14:20.645463Z","end":"2026-04-13T17:14:21.957482Z","steps":["trace[1760391721] 'process raft request' (duration: 1.31179474s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:21.957576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:20.867859Z","time spent":"1.089684006s","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:14:21.957616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:20.645457Z","time spent":"1.312102646s","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:14:21.957595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.267734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:14:21.957672Z","caller":"traceutil/trace.go:171","msg":"trace[1201483024] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:960; }","duration":"823.394117ms","start":"2026-04-13T17:14:21.134258Z","end":"2026-04-13T17:14:21.957652Z","steps":["trace[1201483024] 'agreement among raft nodes before linearized reading' (duration: 823.198733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:21.957701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.465724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T17:14:21.957726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:21.134237Z","time spent":"823.479138ms","remote":"127.0.0.1:41718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":92,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:14:21.957825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.684488ms","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-13T17:14:21.957855Z","caller":"traceutil/trace.go:171","msg":"trace[1349699329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"298.743709ms","start":"2026-04-13T17:14:21.659103Z","end":"2026-04-13T17:14:21.957847Z","steps":["trace[1349699329] 'agreement among raft nodes before linearized reading' (duration: 298.645357ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:21.957733Z","caller":"traceutil/trace.go:171","msg":"trace[1071297293] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:960; }","duration":"413.571727ms","start":"2026-04-13T17:14:21.544152Z","end":"2026-04-13T17:14:21.957724Z","steps":["trace[1071297293] 'agreement among raft nodes before linearized reading' (duration: 413.507805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:21.957911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:21.544127Z","time spent":"413.77448ms","remote":"127.0.0.1:41510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-04-13T17:14:22.23045Z","caller":"traceutil/trace.go:171","msg":"trace[401104841] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"253.527647ms","start":"2026-04-13T17:14:21.976887Z","end":"2026-04-13T17:14:22.230415Z","steps":["trace[401104841] 'read index received' (duration: 248.010378ms)","trace[401104841] 'applied index is now lower than readState.Index' (duration: 5.515769ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:14:22.230604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.69204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:14:22.230637Z","caller":"traceutil/trace.go:171","msg":"trace[553066275] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:961; }","duration":"253.748511ms","start":"2026-04-13T17:14:21.976878Z","end":"2026-04-13T17:14:22.230627Z","steps":["trace[553066275] 'agreement among raft nodes before linearized reading' (duration: 253.667079ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:22.230625Z","caller":"traceutil/trace.go:171","msg":"trace[750710247] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"268.756086ms","start":"2026-04-13T17:14:21.961837Z","end":"2026-04-13T17:14:22.230593Z","steps":["trace[750710247] 'process raft request' (duration: 262.973502ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:45.122555Z","caller":"traceutil/trace.go:171","msg":"trace[1030127884] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"244.184131ms","start":"2026-04-13T17:14:44.878345Z","end":"2026-04-13T17:14:45.122529Z","steps":["trace[1030127884] 'read index received' (duration: 243.921196ms)","trace[1030127884] 'applied index is now lower than readState.Index' (duration: 262.005µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:45.122908Z","caller":"traceutil/trace.go:171","msg":"trace[870037526] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"375.77782ms","start":"2026-04-13T17:14:44.747114Z","end":"2026-04-13T17:14:45.122892Z","steps":["trace[870037526] 'process raft request' (duration: 375.254979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:45.122994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.206534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:14:45.123016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:44.747096Z","time spent":"375.850822ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:14:45.123054Z","caller":"traceutil/trace.go:171","msg":"trace[1565309184] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1036; }","duration":"197.301326ms","start":"2026-04-13T17:14:44.925737Z","end":"2026-04-13T17:14:45.123038Z","steps":["trace[1565309184] 'agreement among raft nodes before linearized reading' (duration: 197.179193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:45.123033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.657851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:14:45.123121Z","caller":"traceutil/trace.go:171","msg":"trace[1236305362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"244.761893ms","start":"2026-04-13T17:14:44.878338Z","end":"2026-04-13T17:14:45.1231Z","steps":["trace[1236305362] 'agreement among raft nodes before linearized reading' (duration: 244.59553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:45.6799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.989489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:14:45.679984Z","caller":"traceutil/trace.go:171","msg":"trace[965812190] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1036; }","duration":"474.133052ms","start":"2026-04-13T17:14:45.205833Z","end":"2026-04-13T17:14:45.679966Z","steps":["trace[965812190] 'count revisions from in-memory index tree' (duration: 473.898776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:45.680037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:45.205811Z","time spent":"474.214593ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-04-13T17:14:45.978714Z","caller":"traceutil/trace.go:171","msg":"trace[288973230] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"294.3049ms","start":"2026-04-13T17:14:45.684328Z","end":"2026-04-13T17:14:45.978633Z","steps":["trace[288973230] 'process raft request' (duration: 294.110077ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:46.142215Z","caller":"traceutil/trace.go:171","msg":"trace[1468777892] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"106.470182ms","start":"2026-04-13T17:14:46.035711Z","end":"2026-04-13T17:14:46.142181Z","steps":["trace[1468777892] 'process raft request' (duration: 106.230167ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:46.266717Z","caller":"traceutil/trace.go:171","msg":"trace[616564525] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"119.269266ms","start":"2026-04-13T17:14:46.14741Z","end":"2026-04-13T17:14:46.266679Z","steps":["trace[616564525] 'process raft request' (duration: 118.99416ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:47.497518Z","caller":"traceutil/trace.go:171","msg":"trace[1456421916] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"366.427995ms","start":"2026-04-13T17:14:47.131069Z","end":"2026-04-13T17:14:47.497497Z","steps":["trace[1456421916] 'process raft request' (duration: 366.290683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:47.497677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:47.131054Z","time spent":"366.541917ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:14:48.168995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.419697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:14:48.169435Z","caller":"traceutil/trace.go:171","msg":"trace[326359057] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"724.732186ms","start":"2026-04-13T17:14:47.444676Z","end":"2026-04-13T17:14:48.169408Z","steps":["trace[326359057] 'process raft request' (duration: 379.802009ms)","trace[326359057] 'compare' (duration: 343.933828ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:48.169455Z","caller":"traceutil/trace.go:171","msg":"trace[2056558267] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1076; }","duration":"686.354229ms","start":"2026-04-13T17:14:47.483073Z","end":"2026-04-13T17:14:48.169427Z","steps":["trace[2056558267] 'read index received' (duration: 14.276633ms)","trace[2056558267] 'applied index is now lower than readState.Index' (duration: 672.075616ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:14:48.169542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:47.444652Z","time spent":"724.846308ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:14:48.214948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.843441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:14:48.215037Z","caller":"traceutil/trace.go:171","msg":"trace[1173266920] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1043; }","duration":"731.999744ms","start":"2026-04-13T17:14:47.483015Z","end":"2026-04-13T17:14:48.215014Z","steps":["trace[1173266920] 'agreement among raft nodes before linearized reading' (duration: 686.676985ms)","trace[1173266920] 'count revisions from in-memory index tree' (duration: 45.140226ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:14:48.215191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:47.482994Z","time spent":"732.083956ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:14:48.222362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.205373ms","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-13T17:14:48.222422Z","caller":"traceutil/trace.go:171","msg":"trace[1166293529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1044; }","duration":"239.300335ms","start":"2026-04-13T17:14:47.983104Z","end":"2026-04-13T17:14:48.222404Z","steps":["trace[1166293529] 'agreement among raft nodes before linearized reading' (duration: 239.163532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:48.222606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.737011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:14:48.222665Z","caller":"traceutil/trace.go:171","msg":"trace[328132827] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1044; }","duration":"261.841973ms","start":"2026-04-13T17:14:47.960809Z","end":"2026-04-13T17:14:48.222651Z","steps":["trace[328132827] 'agreement among raft nodes before linearized reading' (duration: 261.752951ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:50.729472Z","caller":"traceutil/trace.go:171","msg":"trace[159687667] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"471.611156ms","start":"2026-04-13T17:14:50.257836Z","end":"2026-04-13T17:14:50.729447Z","steps":["trace[159687667] 'read index received' (duration: 470.538255ms)","trace[159687667] 'applied index is now lower than readState.Index' (duration: 1.072001ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:50.72954Z","caller":"traceutil/trace.go:171","msg":"trace[2062296406] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"502.157319ms","start":"2026-04-13T17:14:50.227347Z","end":"2026-04-13T17:14:50.729504Z","steps":["trace[2062296406] 'process raft request' (duration: 501.924605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:50.729864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:50.227321Z","time spent":"502.453954ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:14:50.729888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.022043ms","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-13T17:14:50.729946Z","caller":"traceutil/trace.go:171","msg":"trace[939209100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1050; }","duration":"472.129836ms","start":"2026-04-13T17:14:50.2578Z","end":"2026-04-13T17:14:50.72993Z","steps":["trace[939209100] 'agreement among raft nodes before linearized reading' (duration: 471.79007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:50.729964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.095378ms","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-13T17:14:50.729996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:50.257787Z","time spent":"472.195277ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T17:14:50.73003Z","caller":"traceutil/trace.go:171","msg":"trace[702122702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1050; }","duration":"398.198159ms","start":"2026-04-13T17:14:50.331814Z","end":"2026-04-13T17:14:50.730013Z","steps":["trace[702122702] 'agreement among raft nodes before linearized reading' (duration: 398.030136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:50.730009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.078724ms","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-13T17:14:50.730071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:50.331795Z","time spent":"398.264841ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T17:14:50.730086Z","caller":"traceutil/trace.go:171","msg":"trace[2145905796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1050; }","duration":"302.202745ms","start":"2026-04-13T17:14:50.427864Z","end":"2026-04-13T17:14:50.730066Z","steps":["trace[2145905796] 'agreement among raft nodes before linearized reading' (duration: 301.991651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:50.730127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:50.427842Z","time spent":"302.276857ms","remote":"127.0.0.1:41426","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-13T17:14:50.730153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.001692ms","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-13T17:14:50.730213Z","caller":"traceutil/trace.go:171","msg":"trace[1048184861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1050; }","duration":"388.104035ms","start":"2026-04-13T17:14:50.342092Z","end":"2026-04-13T17:14:50.730196Z","steps":["trace[1048184861] 'agreement among raft nodes before linearized reading' (duration: 387.933771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:50.730257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:50.342071Z","time spent":"388.174137ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T17:15:06.597299Z","caller":"traceutil/trace.go:171","msg":"trace[2096506492] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"917.139725ms","start":"2026-04-13T17:15:05.680144Z","end":"2026-04-13T17:15:06.597284Z","steps":["trace[2096506492] 'process raft request' (duration: 917.032253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:06.597393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:05.680124Z","time spent":"917.217946ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:15:19.041556Z","caller":"traceutil/trace.go:171","msg":"trace[1433439142] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"105.913346ms","start":"2026-04-13T17:15:18.93562Z","end":"2026-04-13T17:15:19.041533Z","steps":["trace[1433439142] 'read index received' (duration: 105.695542ms)","trace[1433439142] 'applied index is now lower than readState.Index' (duration: 216.654µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:15:19.041754Z","caller":"traceutil/trace.go:171","msg":"trace[2029490994] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"138.812235ms","start":"2026-04-13T17:15:18.902883Z","end":"2026-04-13T17:15:19.041696Z","steps":["trace[2029490994] 'process raft request' (duration: 138.500479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:19.041808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.173741ms","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-13T17:15:19.041875Z","caller":"traceutil/trace.go:171","msg":"trace[2127190873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1203; }","duration":"106.274953ms","start":"2026-04-13T17:15:18.935585Z","end":"2026-04-13T17:15:19.04186Z","steps":["trace[2127190873] 'agreement among raft nodes before linearized reading' (duration: 106.083699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:19.042223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.053342ms","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-13T17:15:19.042304Z","caller":"traceutil/trace.go:171","msg":"trace[449239030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1203; }","duration":"100.142235ms","start":"2026-04-13T17:15:18.942143Z","end":"2026-04-13T17:15:19.042285Z","steps":["trace[449239030] 'agreement among raft nodes before linearized reading' (duration: 99.719756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:21.550374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512798039082349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:15:21.964585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047834892s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:15:21.96468Z","caller":"traceutil/trace.go:171","msg":"trace[1901764708] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1220; }","duration":"1.047942444s","start":"2026-04-13T17:15:20.916723Z","end":"2026-04-13T17:15:21.964666Z","steps":["trace[1901764708] 'range keys from in-memory index tree' (duration: 1.047820672s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:21.964918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.442101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:15:21.965009Z","caller":"traceutil/trace.go:171","msg":"trace[735468913] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"915.491412ms","start":"2026-04-13T17:15:21.049504Z","end":"2026-04-13T17:15:21.964996Z","steps":["trace[735468913] 'read index received' (duration: 714.806147ms)","trace[735468913] 'applied index is now lower than readState.Index' (duration: 200.683895ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:15:21.965148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.238278ms","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-13T17:15:21.965166Z","caller":"traceutil/trace.go:171","msg":"trace[355029888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1221; }","duration":"913.294449ms","start":"2026-04-13T17:15:21.051867Z","end":"2026-04-13T17:15:21.965161Z","steps":["trace[355029888] 'agreement among raft nodes before linearized reading' (duration: 913.202798ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:21.965175Z","caller":"traceutil/trace.go:171","msg":"trace[322011] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"918.297807ms","start":"2026-04-13T17:15:21.046865Z","end":"2026-04-13T17:15:21.965162Z","steps":["trace[322011] 'process raft request' (duration: 717.550251ms)","trace[322011] 'compare' (duration: 199.90533ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:15:21.965194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:21.051848Z","time spent":"913.34206ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T17:15:21.965258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:21.046847Z","time spent":"918.360838ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:15:21.965341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.839732ms","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-13T17:15:21.965359Z","caller":"traceutil/trace.go:171","msg":"trace[1197633195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1221; }","duration":"911.878272ms","start":"2026-04-13T17:15:21.053476Z","end":"2026-04-13T17:15:21.965354Z","steps":["trace[1197633195] 'agreement among raft nodes before linearized reading' (duration: 911.831201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:21.965372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:21.053466Z","time spent":"911.902832ms","remote":"127.0.0.1:41426","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-13T17:15:21.965344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.818768ms","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-13T17:15:21.965497Z","caller":"traceutil/trace.go:171","msg":"trace[770733704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1221; }","duration":"916.020832ms","start":"2026-04-13T17:15:21.049455Z","end":"2026-04-13T17:15:21.965476Z","steps":["trace[770733704] 'agreement among raft nodes before linearized reading' (duration: 915.618535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:21.96557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:21.049437Z","time spent":"916.119275ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T17:15:21.965446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094375405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:15:21.965722Z","caller":"traceutil/trace.go:171","msg":"trace[57054294] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1220; }","duration":"1.095342194s","start":"2026-04-13T17:15:20.870371Z","end":"2026-04-13T17:15:21.965713Z","steps":["trace[57054294] 'count revisions from in-memory index tree' (duration: 1.094259623s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:21.965744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:20.870325Z","time spent":"1.095411276s","remote":"127.0.0.1:54920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:15:21.965512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.33158ms","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-13T17:15:21.965908Z","caller":"traceutil/trace.go:171","msg":"trace[64342111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1221; }","duration":"913.751359ms","start":"2026-04-13T17:15:21.05214Z","end":"2026-04-13T17:15:21.965892Z","steps":["trace[64342111] 'agreement among raft nodes before linearized reading' (duration: 913.284879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:21.965983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:21.052127Z","time spent":"913.838201ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T17:15:22.211234Z","caller":"traceutil/trace.go:171","msg":"trace[517220765] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"241.675213ms","start":"2026-04-13T17:15:21.969534Z","end":"2026-04-13T17:15:22.211209Z","steps":["trace[517220765] 'process raft request' (duration: 241.54864ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:22.252712Z","caller":"traceutil/trace.go:171","msg":"trace[1516642873] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"163.174557ms","start":"2026-04-13T17:15:22.089523Z","end":"2026-04-13T17:15:22.252697Z","steps":["trace[1516642873] 'process raft request' (duration: 163.141636ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:22.253008Z","caller":"traceutil/trace.go:171","msg":"trace[1670841072] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"283.041775ms","start":"2026-04-13T17:15:21.969959Z","end":"2026-04-13T17:15:22.253001Z","steps":["trace[1670841072] 'process raft request' (duration: 282.546034ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:22.253096Z","caller":"traceutil/trace.go:171","msg":"trace[1422350477] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"283.070275ms","start":"2026-04-13T17:15:21.97002Z","end":"2026-04-13T17:15:22.25309Z","steps":["trace[1422350477] 'process raft request' (duration: 282.582855ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:22.253163Z","caller":"traceutil/trace.go:171","msg":"trace[1893157105] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"283.110766ms","start":"2026-04-13T17:15:21.970048Z","end":"2026-04-13T17:15:22.253158Z","steps":["trace[1893157105] 'process raft request' (duration: 282.586165ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:22.946539Z","caller":"traceutil/trace.go:171","msg":"trace[2129452393] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"164.565195ms","start":"2026-04-13T17:15:22.781953Z","end":"2026-04-13T17:15:22.946518Z","steps":["trace[2129452393] 'read index received' (duration: 164.410662ms)","trace[2129452393] 'applied index is now lower than readState.Index' (duration: 153.693µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:15:22.946712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.752199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T17:15:22.946743Z","caller":"traceutil/trace.go:171","msg":"trace[814992793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1226; }","duration":"164.84315ms","start":"2026-04-13T17:15:22.781892Z","end":"2026-04-13T17:15:22.946735Z","steps":["trace[814992793] 'agreement among raft nodes before linearized reading' (duration: 164.707537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:23.678178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.131384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:15:23.678248Z","caller":"traceutil/trace.go:171","msg":"trace[1583130069] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"729.991982ms","start":"2026-04-13T17:15:22.948245Z","end":"2026-04-13T17:15:23.678237Z","steps":["trace[1583130069] 'process raft request' (duration: 611.741626ms)","trace[1583130069] 'compare' (duration: 117.991431ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:15:23.678281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:22.948221Z","time spent":"730.044363ms","remote":"127.0.0.1:41194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:15:24.304903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.7186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:15:24.305464Z","caller":"traceutil/trace.go:171","msg":"trace[1149748252] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1271; }","duration":"1.237947883s","start":"2026-04-13T17:15:23.067491Z","end":"2026-04-13T17:15:24.305439Z","steps":["trace[1149748252] 'read index received' (duration: 492.507061ms)","trace[1149748252] 'applied index is now lower than readState.Index' (duration: 745.438802ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:15:24.305392Z","caller":"traceutil/trace.go:171","msg":"trace[656144893] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"335.35435ms","start":"2026-04-13T17:15:23.97002Z","end":"2026-04-13T17:15:24.305375Z","steps":["trace[656144893] 'process raft request' (duration: 335.267998ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:24.305582Z","caller":"traceutil/trace.go:171","msg":"trace[1143863723] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"1.352574698s","start":"2026-04-13T17:15:22.95294Z","end":"2026-04-13T17:15:24.305515Z","steps":["trace[1143863723] 'process raft request' (duration: 1.122175255s)","trace[1143863723] 'compare' (duration: 229.411323ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:15:24.30567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238160077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:15:24.30575Z","caller":"traceutil/trace.go:171","msg":"trace[1837029118] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1229; }","duration":"1.238256969s","start":"2026-04-13T17:15:23.067476Z","end":"2026-04-13T17:15:24.305733Z","steps":["trace[1837029118] 'agreement among raft nodes before linearized reading' (duration: 1.238103916s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:24.305799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:23.067415Z","time spent":"1.238373461s","remote":"127.0.0.1:41352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:15:24.305826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.940108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:15:24.305867Z","caller":"traceutil/trace.go:171","msg":"trace[220263710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1229; }","duration":"429.002439ms","start":"2026-04-13T17:15:23.876853Z","end":"2026-04-13T17:15:24.305855Z","steps":["trace[220263710] 'agreement among raft nodes before linearized reading' (duration: 428.956898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:24.305898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:23.876838Z","time spent":"429.05447ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:15:24.305954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:22.952924Z","time spent":"1.352837922s","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:15:24.30561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:23.970004Z","time spent":"335.564975ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:15:24.305667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.067413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-13T17:15:24.30669Z","caller":"traceutil/trace.go:171","msg":"trace[139404377] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1229; }","duration":"627.111723ms","start":"2026-04-13T17:15:23.679565Z","end":"2026-04-13T17:15:24.306677Z","steps":["trace[139404377] 'agreement among raft nodes before linearized reading' (duration: 626.058814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:24.306739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:23.679554Z","time spent":"627.172094ms","remote":"127.0.0.1:41316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-04-13T17:15:24.604341Z","caller":"traceutil/trace.go:171","msg":"trace[74703861] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"163.111826ms","start":"2026-04-13T17:15:24.441209Z","end":"2026-04-13T17:15:24.604321Z","steps":["trace[74703861] 'process raft request' (duration: 162.977504ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:24.683657Z","caller":"traceutil/trace.go:171","msg":"trace[2044054986] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"124.705771ms","start":"2026-04-13T17:15:24.558913Z","end":"2026-04-13T17:15:24.683619Z","steps":["trace[2044054986] 'process raft request' (duration: 70.152912ms)","trace[2044054986] 'compare' (duration: 54.422577ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:15:24.985973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.541591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:15:24.986051Z","caller":"traceutil/trace.go:171","msg":"trace[1832983199] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"108.885614ms","start":"2026-04-13T17:15:24.877153Z","end":"2026-04-13T17:15:24.986039Z","steps":["trace[1832983199] 'read index received' (duration: 45.551µs)","trace[1832983199] 'applied index is now lower than readState.Index' (duration: 108.838953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:15:24.986117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.965945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:15:24.986138Z","caller":"traceutil/trace.go:171","msg":"trace[1588071766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"109.029027ms","start":"2026-04-13T17:15:24.877103Z","end":"2026-04-13T17:15:24.986132Z","steps":["trace[1588071766] 'agreement among raft nodes before linearized reading' (duration: 108.967676ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:24.986307Z","caller":"traceutil/trace.go:171","msg":"trace[564170856] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"251.737668ms","start":"2026-04-13T17:15:24.734535Z","end":"2026-04-13T17:15:24.986272Z","steps":["trace[564170856] 'process raft request' (duration: 78.841761ms)","trace[564170856] 'compare' (duration: 172.323925ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:15:25.278098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.810678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T17:15:25.278143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.888194ms","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-13T17:15:25.278157Z","caller":"traceutil/trace.go:171","msg":"trace[500989291] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1237; }","duration":"165.88197ms","start":"2026-04-13T17:15:25.112259Z","end":"2026-04-13T17:15:25.278141Z","steps":["trace[500989291] 'count revisions from in-memory index tree' (duration: 165.732227ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:25.278179Z","caller":"traceutil/trace.go:171","msg":"trace[212702954] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1237; }","duration":"160.942115ms","start":"2026-04-13T17:15:25.117231Z","end":"2026-04-13T17:15:25.278173Z","steps":["trace[212702954] 'count revisions from in-memory index tree' (duration: 160.851623ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:26.517956Z","caller":"traceutil/trace.go:171","msg":"trace[1019961926] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"205.557221ms","start":"2026-04-13T17:15:26.31237Z","end":"2026-04-13T17:15:26.517927Z","steps":["trace[1019961926] 'process raft request' (duration: 205.399348ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:26.52029Z","caller":"traceutil/trace.go:171","msg":"trace[93076046] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"205.429717ms","start":"2026-04-13T17:15:26.314821Z","end":"2026-04-13T17:15:26.52025Z","steps":["trace[93076046] 'process raft request' (duration: 205.165592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:26.520657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.765741ms","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-13T17:15:26.520743Z","caller":"traceutil/trace.go:171","msg":"trace[1710722370] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1239; }","duration":"162.904253ms","start":"2026-04-13T17:15:26.357815Z","end":"2026-04-13T17:15:26.52072Z","steps":["trace[1710722370] 'agreement among raft nodes before linearized reading' (duration: 162.71954ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:26.520183Z","caller":"traceutil/trace.go:171","msg":"trace[451883307] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"162.267081ms","start":"2026-04-13T17:15:26.357884Z","end":"2026-04-13T17:15:26.520152Z","steps":["trace[451883307] 'read index received' (duration: 160.71368ms)","trace[451883307] 'applied index is now lower than readState.Index' (duration: 1.552211ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:15:26.521743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.423499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-13T17:15:26.521746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.712155ms","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-13T17:15:26.521802Z","caller":"traceutil/trace.go:171","msg":"trace[1018728798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1239; }","duration":"117.558622ms","start":"2026-04-13T17:15:26.404229Z","end":"2026-04-13T17:15:26.521787Z","steps":["trace[1018728798] 'agreement among raft nodes before linearized reading' (duration: 117.344567ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:26.521842Z","caller":"traceutil/trace.go:171","msg":"trace[161805488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"117.817248ms","start":"2026-04-13T17:15:26.404003Z","end":"2026-04-13T17:15:26.521821Z","steps":["trace[161805488] 'agreement among raft nodes before linearized reading' (duration: 117.566052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:26.526875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.705783ms","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-13T17:15:26.527019Z","caller":"traceutil/trace.go:171","msg":"trace[1989192878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"122.887226ms","start":"2026-04-13T17:15:26.404091Z","end":"2026-04-13T17:15:26.526978Z","steps":["trace[1989192878] 'agreement among raft nodes before linearized reading' (duration: 117.927671ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:34.241589Z","caller":"traceutil/trace.go:171","msg":"trace[1187271967] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"125.549618ms","start":"2026-04-13T17:15:34.116017Z","end":"2026-04-13T17:15:34.241567Z","steps":["trace[1187271967] 'read index received' (duration: 125.384845ms)","trace[1187271967] 'applied index is now lower than readState.Index' (duration: 163.783µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:15:34.241654Z","caller":"traceutil/trace.go:171","msg":"trace[1078778239] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"161.099797ms","start":"2026-04-13T17:15:34.08052Z","end":"2026-04-13T17:15:34.24162Z","steps":["trace[1078778239] 'process raft request' (duration: 160.908843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:34.241902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.857034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:15:34.242006Z","caller":"traceutil/trace.go:171","msg":"trace[30043773] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1307; }","duration":"125.956855ms","start":"2026-04-13T17:15:34.115981Z","end":"2026-04-13T17:15:34.241938Z","steps":["trace[30043773] 'agreement among raft nodes before linearized reading' (duration: 125.857833ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:34.694307Z","caller":"traceutil/trace.go:171","msg":"trace[1446314340] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"281.32178ms","start":"2026-04-13T17:15:34.412968Z","end":"2026-04-13T17:15:34.69429Z","steps":["trace[1446314340] 'read index received' (duration: 274.777564ms)","trace[1446314340] 'applied index is now lower than readState.Index' (duration: 6.543056ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:15:34.694517Z","caller":"traceutil/trace.go:171","msg":"trace[1370656015] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"447.817583ms","start":"2026-04-13T17:15:34.246685Z","end":"2026-04-13T17:15:34.694503Z","steps":["trace[1370656015] 'process raft request' (duration: 441.050042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:34.694605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:15:34.246664Z","time spent":"447.881865ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:15:34.69464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.654697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-04-13T17:15:34.694691Z","caller":"traceutil/trace.go:171","msg":"trace[1229158171] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1308; }","duration":"281.741449ms","start":"2026-04-13T17:15:34.412935Z","end":"2026-04-13T17:15:34.694677Z","steps":["trace[1229158171] 'agreement among raft nodes before linearized reading' (duration: 281.566936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:34.697383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.268599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T17:15:34.697434Z","caller":"traceutil/trace.go:171","msg":"trace[1238094980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1310; }","duration":"148.3626ms","start":"2026-04-13T17:15:34.549059Z","end":"2026-04-13T17:15:34.697421Z","steps":["trace[1238094980] 'agreement among raft nodes before linearized reading' (duration: 148.230427ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:34.697599Z","caller":"traceutil/trace.go:171","msg":"trace[431250301] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"158.535937ms","start":"2026-04-13T17:15:34.539052Z","end":"2026-04-13T17:15:34.697588Z","steps":["trace[431250301] 'process raft request' (duration: 158.094089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:34.697652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.512131ms","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-13T17:15:34.697712Z","caller":"traceutil/trace.go:171","msg":"trace[729067945] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"135.592762ms","start":"2026-04-13T17:15:34.562101Z","end":"2026-04-13T17:15:34.697694Z","steps":["trace[729067945] 'agreement among raft nodes before linearized reading' (duration: 135.451999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:34.697818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.379866ms","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-13T17:15:34.697842Z","caller":"traceutil/trace.go:171","msg":"trace[1854928222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1310; }","duration":"138.419248ms","start":"2026-04-13T17:15:34.559416Z","end":"2026-04-13T17:15:34.697835Z","steps":["trace[1854928222] 'agreement among raft nodes before linearized reading' (duration: 138.347766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:34.697837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.918093ms","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-13T17:15:34.697868Z","caller":"traceutil/trace.go:171","msg":"trace[596433287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1310; }","duration":"144.991825ms","start":"2026-04-13T17:15:34.552868Z","end":"2026-04-13T17:15:34.69786Z","steps":["trace[596433287] 'agreement among raft nodes before linearized reading' (duration: 144.904983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:34.69796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.11499ms","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-13T17:15:34.698022Z","caller":"traceutil/trace.go:171","msg":"trace[1225962905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1310; }","duration":"140.170591ms","start":"2026-04-13T17:15:34.557812Z","end":"2026-04-13T17:15:34.697983Z","steps":["trace[1225962905] 'agreement among raft nodes before linearized reading' (duration: 140.070549ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:37.728056Z","caller":"traceutil/trace.go:171","msg":"trace[1980067965] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"109.60113ms","start":"2026-04-13T17:18:37.618428Z","end":"2026-04-13T17:18:37.728029Z","steps":["trace[1980067965] 'process raft request' (duration: 109.326385ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:37.858923Z","caller":"traceutil/trace.go:171","msg":"trace[1048586521] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"125.896722ms","start":"2026-04-13T17:18:37.73299Z","end":"2026-04-13T17:18:37.858887Z","steps":["trace[1048586521] 'process raft request' (duration: 101.645537ms)","trace[1048586521] 'compare' (duration: 23.949949ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:18:37.859242Z","caller":"traceutil/trace.go:171","msg":"trace[161185932] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"125.81677ms","start":"2026-04-13T17:18:37.733406Z","end":"2026-04-13T17:18:37.859222Z","steps":["trace[161185932] 'process raft request' (duration: 125.613236ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:37.859282Z","caller":"traceutil/trace.go:171","msg":"trace[264447848] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"125.757769ms","start":"2026-04-13T17:18:37.733485Z","end":"2026-04-13T17:18:37.859243Z","steps":["trace[264447848] 'process raft request' (duration: 125.609457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:18:37.859736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.650598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:18:37.859806Z","caller":"traceutil/trace.go:171","msg":"trace[2073245250] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2264; }","duration":"111.76093ms","start":"2026-04-13T17:18:37.748029Z","end":"2026-04-13T17:18:37.85979Z","steps":["trace[2073245250] 'agreement among raft nodes before linearized reading' (duration: 111.626588ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:37.859995Z","caller":"traceutil/trace.go:171","msg":"trace[1133885355] linearizableReadLoop","detail":"{readStateIndex:2348; appliedIndex:2344; }","duration":"111.908444ms","start":"2026-04-13T17:18:37.748065Z","end":"2026-04-13T17:18:37.859974Z","steps":["trace[1133885355] 'read index received' (duration: 6.76965ms)","trace[1133885355] 'applied index is now lower than readState.Index' (duration: 105.135794ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:18:46.030902Z","caller":"traceutil/trace.go:171","msg":"trace[720435422] linearizableReadLoop","detail":"{readStateIndex:2393; appliedIndex:2392; }","duration":"240.222161ms","start":"2026-04-13T17:18:45.790657Z","end":"2026-04-13T17:18:46.030879Z","steps":["trace[720435422] 'read index received' (duration: 239.967456ms)","trace[720435422] 'applied index is now lower than readState.Index' (duration: 253.955µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:18:46.031198Z","caller":"traceutil/trace.go:171","msg":"trace[908648969] transaction","detail":"{read_only:false; response_revision:2307; number_of_response:1; }","duration":"267.511784ms","start":"2026-04-13T17:18:45.763677Z","end":"2026-04-13T17:18:46.031189Z","steps":["trace[908648969] 'process raft request' (duration: 267.054854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:18:46.031465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.496651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:18:46.031621Z","caller":"traceutil/trace.go:171","msg":"trace[187254082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2307; }","duration":"153.673803ms","start":"2026-04-13T17:18:45.87788Z","end":"2026-04-13T17:18:46.031554Z","steps":["trace[187254082] 'agreement among raft nodes before linearized reading' (duration: 153.437349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:18:46.031644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.686766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-13T17:18:46.031671Z","caller":"traceutil/trace.go:171","msg":"trace[210553962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2307; }","duration":"140.769746ms","start":"2026-04-13T17:18:45.890893Z","end":"2026-04-13T17:18:46.031663Z","steps":["trace[210553962] 'agreement among raft nodes before linearized reading' (duration: 140.646954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:18:46.031668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.994296ms","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-13T17:18:46.031981Z","caller":"traceutil/trace.go:171","msg":"trace[1937039096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2307; }","duration":"241.354312ms","start":"2026-04-13T17:18:45.790606Z","end":"2026-04-13T17:18:46.03196Z","steps":["trace[1937039096] 'agreement among raft nodes before linearized reading' (duration: 240.853763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:18:46.032223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.92768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-13T17:18:46.03224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.626613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T17:18:46.032266Z","caller":"traceutil/trace.go:171","msg":"trace[574961454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2307; }","duration":"140.683884ms","start":"2026-04-13T17:18:45.891574Z","end":"2026-04-13T17:18:46.032258Z","steps":["trace[574961454] 'agreement among raft nodes before linearized reading' (duration: 139.954281ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:46.032263Z","caller":"traceutil/trace.go:171","msg":"trace[2108540360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2307; }","duration":"140.005191ms","start":"2026-04-13T17:18:45.892248Z","end":"2026-04-13T17:18:46.032254Z","steps":["trace[2108540360] 'agreement among raft nodes before linearized reading' (duration: 139.107405ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:49.768494Z","caller":"traceutil/trace.go:171","msg":"trace[1340664510] linearizableReadLoop","detail":"{readStateIndex:2414; appliedIndex:2413; }","duration":"154.286006ms","start":"2026-04-13T17:18:49.614183Z","end":"2026-04-13T17:18:49.768469Z","steps":["trace[1340664510] 'read index received' (duration: 153.98725ms)","trace[1340664510] 'applied index is now lower than readState.Index' (duration: 297.696µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:18:49.768612Z","caller":"traceutil/trace.go:171","msg":"trace[641269870] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"162.831039ms","start":"2026-04-13T17:18:49.60577Z","end":"2026-04-13T17:18:49.768601Z","steps":["trace[641269870] 'process raft request' (duration: 162.552574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:18:49.768869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.658232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:18:49.768953Z","caller":"traceutil/trace.go:171","msg":"trace[1512413549] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2327; }","duration":"154.774274ms","start":"2026-04-13T17:18:49.61416Z","end":"2026-04-13T17:18:49.768934Z","steps":["trace[1512413549] 'agreement among raft nodes before linearized reading' (duration: 154.617331ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:52.377698Z","caller":"traceutil/trace.go:171","msg":"trace[1120591052] transaction","detail":"{read_only:false; response_revision:2345; number_of_response:1; }","duration":"144.101711ms","start":"2026-04-13T17:18:52.233571Z","end":"2026-04-13T17:18:52.377673Z","steps":["trace[1120591052] 'process raft request' (duration: 143.811285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:18:52.565675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.773952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:18:52.565893Z","caller":"traceutil/trace.go:171","msg":"trace[36704933] transaction","detail":"{read_only:false; response_revision:2346; number_of_response:1; }","duration":"331.956348ms","start":"2026-04-13T17:18:52.233909Z","end":"2026-04-13T17:18:52.565865Z","steps":["trace[36704933] 'process raft request' (duration: 172.909171ms)","trace[36704933] 'compare' (duration: 158.597669ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:18:52.566008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:18:52.233889Z","time spent":"332.06014ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:18:52.566227Z","caller":"traceutil/trace.go:171","msg":"trace[1128105359] transaction","detail":"{read_only:false; response_revision:2347; number_of_response:1; }","duration":"159.111218ms","start":"2026-04-13T17:18:52.407097Z","end":"2026-04-13T17:18:52.566208Z","steps":["trace[1128105359] 'process raft request' (duration: 158.67563ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:53.189077Z","caller":"traceutil/trace.go:171","msg":"trace[1066256245] transaction","detail":"{read_only:false; response_revision:2349; number_of_response:1; }","duration":"160.711499ms","start":"2026-04-13T17:18:53.028289Z","end":"2026-04-13T17:18:53.189001Z","steps":["trace[1066256245] 'process raft request' (duration: 160.515985ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:53.305784Z","caller":"traceutil/trace.go:171","msg":"trace[1835683240] transaction","detail":"{read_only:false; response_revision:2350; number_of_response:1; }","duration":"109.314494ms","start":"2026-04-13T17:18:53.196441Z","end":"2026-04-13T17:18:53.305756Z","steps":["trace[1835683240] 'process raft request' (duration: 109.149381ms)"],"step_count":1}