{"level":"warn","ts":"2026-04-16T00:31:32.288583Z","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-16T00:31:32.288694Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-16T00:31:32.288828Z","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-16T00:31:32.288847Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-04-16T00:31:32.288886Z","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-16T00:31:32.289742Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-04-16T00:31:32.289893Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-16T00:31:32.368816Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"78.636735ms"} {"level":"info","ts":"2026-04-16T00:31:32.379597Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-04-16T00:31:32.379744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T00:31:32.379809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-04-16T00:31:32.37983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T00:31:32.379843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-04-16T00:31:32.379907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-04-16T00:31:32.390675Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T00:31:32.395321Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T00:31:32.39689Z","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-16T00:31:32.399236Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T00:31:32.399354Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T00:31:32.399583Z","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-16T00:31:32.399658Z","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-16T00:31:32.399673Z","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-16T00:31:32.400418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-04-16T00:31:32.401705Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-04-16T00:31:32.403802Z","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-16T00:31:32.403993Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-04-16T00:31:32.404063Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-04-16T00:31:32.404286Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T00:31:32.404413Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T00:31:32.980554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T00:31:32.980609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T00:31:32.98068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-04-16T00:31:32.980709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-04-16T00:31:32.98072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-04-16T00:31:32.980763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-04-16T00:31:32.980785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-04-16T00:31:33.058861Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T00:31:33.063606Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T00:31:33.063627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T00:31:33.063671Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T00:31:33.064Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T00:31:33.064048Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T00:31:33.066636Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T00:31:33.070074Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-04-16T00:31:33.111986Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T00:31:33.11214Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T00:31:33.112173Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T00:31:34.464726Z","caller":"traceutil/trace.go:171","msg":"trace[568914004] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"104.048955ms","start":"2026-04-16T00:31:34.360655Z","end":"2026-04-16T00:31:34.464704Z","steps":["trace[568914004] 'process raft request' (duration: 103.797931ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:34.464827Z","caller":"traceutil/trace.go:171","msg":"trace[1322075400] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"112.481075ms","start":"2026-04-16T00:31:34.352295Z","end":"2026-04-16T00:31:34.464776Z","steps":["trace[1322075400] 'process raft request' (duration: 48.848359ms)","trace[1322075400] 'compare' (duration: 63.167787ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:31:35.579326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.01315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-16T00:31:35.579461Z","caller":"traceutil/trace.go:171","msg":"trace[466800661] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"212.955768ms","start":"2026-04-16T00:31:35.366487Z","end":"2026-04-16T00:31:35.579443Z","steps":["trace[466800661] 'process raft request' (duration: 57.337586ms)","trace[466800661] 'compare' (duration: 154.874727ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:31:37.313662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.234493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:31:37.313971Z","caller":"traceutil/trace.go:171","msg":"trace[108817746] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"112.646591ms","start":"2026-04-16T00:31:37.201107Z","end":"2026-04-16T00:31:37.313753Z","steps":["trace[108817746] 'compare' (duration: 106.11004ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:37.51321Z","caller":"traceutil/trace.go:171","msg":"trace[1106171787] linearizableReadLoop","detail":"{readStateIndex:234; appliedIndex:233; }","duration":"113.942247ms","start":"2026-04-16T00:31:37.399241Z","end":"2026-04-16T00:31:37.513183Z","steps":["trace[1106171787] 'read index received' (duration: 42.835912ms)","trace[1106171787] 'applied index is now lower than readState.Index' (duration: 71.104925ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:31:37.513359Z","caller":"traceutil/trace.go:171","msg":"trace[1246941096] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"144.530455ms","start":"2026-04-16T00:31:37.368798Z","end":"2026-04-16T00:31:37.513328Z","steps":["trace[1246941096] 'process raft request' (duration: 73.352629ms)","trace[1246941096] 'compare' (duration: 70.917331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:31:37.513392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.116941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:31:37.513557Z","caller":"traceutil/trace.go:171","msg":"trace[110023357] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:229; }","duration":"114.296134ms","start":"2026-04-16T00:31:37.3992Z","end":"2026-04-16T00:31:37.513496Z","steps":["trace[110023357] 'agreement among raft nodes before linearized reading' (duration: 114.09961ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:39.230951Z","caller":"traceutil/trace.go:171","msg":"trace[421330268] transaction","detail":"{read_only:false; number_of_response:0; response_revision:317; }","duration":"121.339281ms","start":"2026-04-16T00:31:39.109578Z","end":"2026-04-16T00:31:39.230917Z","steps":["trace[421330268] 'process raft request' (duration: 121.241279ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:39.230923Z","caller":"traceutil/trace.go:171","msg":"trace[2001563191] transaction","detail":"{read_only:false; number_of_response:0; response_revision:317; }","duration":"122.113887ms","start":"2026-04-16T00:31:39.108779Z","end":"2026-04-16T00:31:39.230893Z","steps":["trace[2001563191] 'process raft request' (duration: 121.2764ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:39.231006Z","caller":"traceutil/trace.go:171","msg":"trace[56070745] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"109.287614ms","start":"2026-04-16T00:31:39.121694Z","end":"2026-04-16T00:31:39.230982Z","steps":["trace[56070745] 'process raft request' (duration: 109.203412ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:39.231072Z","caller":"traceutil/trace.go:171","msg":"trace[1157431849] transaction","detail":"{read_only:false; number_of_response:0; response_revision:317; }","duration":"110.902054ms","start":"2026-04-16T00:31:39.120132Z","end":"2026-04-16T00:31:39.231034Z","steps":["trace[1157431849] 'process raft request' (duration: 110.719101ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:39.25348Z","caller":"traceutil/trace.go:171","msg":"trace[2011842669] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"113.337114ms","start":"2026-04-16T00:31:39.14013Z","end":"2026-04-16T00:31:39.253467Z","steps":["trace[2011842669] 'process raft request' (duration: 113.197421ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:39.253732Z","caller":"traceutil/trace.go:171","msg":"trace[2014922753] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"104.699863ms","start":"2026-04-16T00:31:39.148998Z","end":"2026-04-16T00:31:39.253698Z","steps":["trace[2014922753] 'process raft request' (duration: 104.422517ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:39.395181Z","caller":"traceutil/trace.go:171","msg":"trace[666460054] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"132.613205ms","start":"2026-04-16T00:31:39.26254Z","end":"2026-04-16T00:31:39.395153Z","steps":["trace[666460054] 'process raft request' (duration: 132.000733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:31:39.647631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.421275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:31:39.647748Z","caller":"traceutil/trace.go:171","msg":"trace[438819664] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"213.826403ms","start":"2026-04-16T00:31:39.433903Z","end":"2026-04-16T00:31:39.647729Z","steps":["trace[438819664] 'process raft request' (duration: 101.093961ms)","trace[438819664] 'compare' (duration: 112.296973ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:31:39.6522Z","caller":"traceutil/trace.go:171","msg":"trace[769777029] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:334; }","duration":"125.3393ms","start":"2026-04-16T00:31:39.526843Z","end":"2026-04-16T00:31:39.652182Z","steps":["trace[769777029] 'read index received' (duration: 8.107391ms)","trace[769777029] 'applied index is now lower than readState.Index' (duration: 117.231109ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:31:39.652324Z","caller":"traceutil/trace.go:171","msg":"trace[228361033] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"202.07841ms","start":"2026-04-16T00:31:39.450212Z","end":"2026-04-16T00:31:39.65229Z","steps":["trace[228361033] 'process raft request' (duration: 201.878765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:31:39.652407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.557035ms","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-16T00:31:39.652449Z","caller":"traceutil/trace.go:171","msg":"trace[735022075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:325; }","duration":"125.643106ms","start":"2026-04-16T00:31:39.526795Z","end":"2026-04-16T00:31:39.652438Z","steps":["trace[735022075] 'agreement among raft nodes before linearized reading' (duration: 125.496914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:31:40.114301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.759766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:31:40.114508Z","caller":"traceutil/trace.go:171","msg":"trace[43873503] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:333; }","duration":"114.949119ms","start":"2026-04-16T00:31:39.999452Z","end":"2026-04-16T00:31:40.114401Z","steps":["trace[43873503] 'range keys from in-memory index tree' (duration: 114.664964ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:41.60906Z","caller":"traceutil/trace.go:171","msg":"trace[889970218] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:368; }","duration":"107.976177ms","start":"2026-04-16T00:31:41.501064Z","end":"2026-04-16T00:31:41.60904Z","steps":["trace[889970218] 'read index received' (duration: 43.14986ms)","trace[889970218] 'applied index is now lower than readState.Index' (duration: 64.825547ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:31:41.609152Z","caller":"traceutil/trace.go:171","msg":"trace[493531767] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"158.167136ms","start":"2026-04-16T00:31:41.450977Z","end":"2026-04-16T00:31:41.609144Z","steps":["trace[493531767] 'process raft request' (duration: 158.009362ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:41.609169Z","caller":"traceutil/trace.go:171","msg":"trace[1298428452] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"158.234597ms","start":"2026-04-16T00:31:41.450901Z","end":"2026-04-16T00:31:41.609135Z","steps":["trace[1298428452] 'process raft request' (duration: 93.299858ms)","trace[1298428452] 'compare' (duration: 64.680703ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:31:41.609275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.800293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-16T00:31:41.609666Z","caller":"traceutil/trace.go:171","msg":"trace[1132703396] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:359; }","duration":"109.902144ms","start":"2026-04-16T00:31:41.499419Z","end":"2026-04-16T00:31:41.609321Z","steps":["trace[1132703396] 'agreement among raft nodes before linearized reading' (duration: 109.777692ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:41.623159Z","caller":"traceutil/trace.go:171","msg":"trace[1181515644] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"112.493296ms","start":"2026-04-16T00:31:41.510645Z","end":"2026-04-16T00:31:41.623139Z","steps":["trace[1181515644] 'process raft request' (duration: 112.339352ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:41.918066Z","caller":"traceutil/trace.go:171","msg":"trace[306576811] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"180.419154ms","start":"2026-04-16T00:31:41.737601Z","end":"2026-04-16T00:31:41.91802Z","steps":["trace[306576811] 'process raft request' (duration: 112.595339ms)","trace[306576811] 'compare' (duration: 67.544439ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:31:41.918073Z","caller":"traceutil/trace.go:171","msg":"trace[1442854537] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"179.607068ms","start":"2026-04-16T00:31:41.73843Z","end":"2026-04-16T00:31:41.918037Z","steps":["trace[1442854537] 'process raft request' (duration: 179.492315ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:41.918148Z","caller":"traceutil/trace.go:171","msg":"trace[296608409] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"173.15224ms","start":"2026-04-16T00:31:41.744968Z","end":"2026-04-16T00:31:41.91812Z","steps":["trace[296608409] 'process raft request' (duration: 172.991647ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:41.918248Z","caller":"traceutil/trace.go:171","msg":"trace[997039640] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"151.994743ms","start":"2026-04-16T00:31:41.766183Z","end":"2026-04-16T00:31:41.918178Z","steps":["trace[997039640] 'process raft request' (duration: 151.82909ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:41.918077Z","caller":"traceutil/trace.go:171","msg":"trace[1223549186] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:385; }","duration":"124.271288ms","start":"2026-04-16T00:31:41.793783Z","end":"2026-04-16T00:31:41.918054Z","steps":["trace[1223549186] 'read index received' (duration: 56.32387ms)","trace[1223549186] 'applied index is now lower than readState.Index' (duration: 67.946538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:31:41.918504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.648784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-04-16T00:31:41.918779Z","caller":"traceutil/trace.go:171","msg":"trace[1183078357] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:378; }","duration":"124.95929ms","start":"2026-04-16T00:31:41.793742Z","end":"2026-04-16T00:31:41.918701Z","steps":["trace[1183078357] 'agreement among raft nodes before linearized reading' (duration: 124.574232ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:44.994356Z","caller":"traceutil/trace.go:171","msg":"trace[1732615014] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"185.925165ms","start":"2026-04-16T00:31:44.808404Z","end":"2026-04-16T00:31:44.994329Z","steps":["trace[1732615014] 'process raft request' (duration: 172.644506ms)","trace[1732615014] 'compare' (duration: 13.147537ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:31:49.502469Z","caller":"traceutil/trace.go:171","msg":"trace[1208725952] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"193.539483ms","start":"2026-04-16T00:31:49.308899Z","end":"2026-04-16T00:31:49.502438Z","steps":["trace[1208725952] 'process raft request' (duration: 193.39652ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:50.48243Z","caller":"traceutil/trace.go:171","msg":"trace[1962918239] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"110.8632ms","start":"2026-04-16T00:31:50.371541Z","end":"2026-04-16T00:31:50.482404Z","steps":["trace[1962918239] 'process raft request' (duration: 110.37732ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:54.123743Z","caller":"traceutil/trace.go:171","msg":"trace[1892880102] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:468; }","duration":"327.915023ms","start":"2026-04-16T00:31:53.795808Z","end":"2026-04-16T00:31:54.123723Z","steps":["trace[1892880102] 'read index received' (duration: 327.7452ms)","trace[1892880102] 'applied index is now lower than readState.Index' (duration: 169.003µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:31:54.12388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.053556ms","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-16T00:31:54.123865Z","caller":"traceutil/trace.go:171","msg":"trace[1184050586] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"605.302433ms","start":"2026-04-16T00:31:53.518532Z","end":"2026-04-16T00:31:54.123834Z","steps":["trace[1184050586] 'process raft request' (duration: 605.062947ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:31:54.123917Z","caller":"traceutil/trace.go:171","msg":"trace[1564746048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:454; }","duration":"328.133817ms","start":"2026-04-16T00:31:53.795771Z","end":"2026-04-16T00:31:54.123905Z","steps":["trace[1564746048] 'agreement among raft nodes before linearized reading' (duration: 328.060117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:31:54.1239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.646426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:31:54.123959Z","caller":"traceutil/trace.go:171","msg":"trace[1874504201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:454; }","duration":"111.728549ms","start":"2026-04-16T00:31:54.012211Z","end":"2026-04-16T00:31:54.123939Z","steps":["trace[1874504201] 'agreement among raft nodes before linearized reading' (duration: 111.666647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:31:54.123974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:31:53.795753Z","time spent":"328.208129ms","remote":"127.0.0.1:35246","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-16T00:31:54.124048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:31:53.518516Z","time spent":"605.424735ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:31:54.301309Z","caller":"traceutil/trace.go:171","msg":"trace[2002203657] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"172.41682ms","start":"2026-04-16T00:31:54.12887Z","end":"2026-04-16T00:31:54.301287Z","steps":["trace[2002203657] 'process raft request' (duration: 169.328751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:31:59.040043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605174813728813,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T00:31:59.419253Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.097997871s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T00:31:59.419605Z","caller":"traceutil/trace.go:171","msg":"trace[834835225] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.098395809s","start":"2026-04-16T00:31:58.321185Z","end":"2026-04-16T00:31:59.419581Z","steps":["trace[834835225] 'process raft request' (duration: 1.098259465s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:31:59.419747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:31:58.321172Z","time spent":"1.098502882s","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:32:00.174683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.125031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:32:00.174784Z","caller":"traceutil/trace.go:171","msg":"trace[446510892] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:514; }","duration":"1.634793748s","start":"2026-04-16T00:31:58.539974Z","end":"2026-04-16T00:32:00.174768Z","steps":["trace[446510892] 'read index received' (duration: 879.462191ms)","trace[446510892] 'applied index is now lower than readState.Index' (duration: 755.330387ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:00.174795Z","caller":"traceutil/trace.go:171","msg":"trace[1354215063] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.80399274s","start":"2026-04-16T00:31:58.370782Z","end":"2026-04-16T00:32:00.174775Z","steps":["trace[1354215063] 'process raft request' (duration: 1.701678645s)","trace[1354215063] 'compare' (duration: 101.786285ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:32:00.174877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:31:58.370762Z","time spent":"1.804075241s","remote":"127.0.0.1:35246","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-16T00:32:00.174893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.634919701s","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-16T00:32:00.174921Z","caller":"traceutil/trace.go:171","msg":"trace[845176909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:500; }","duration":"1.635009932s","start":"2026-04-16T00:31:58.539901Z","end":"2026-04-16T00:32:00.174911Z","steps":["trace[845176909] 'agreement among raft nodes before linearized reading' (duration: 1.634949472s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:00.17495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:31:58.539879Z","time spent":"1.635064243s","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T00:32:00.175057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161085986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:32:00.175082Z","caller":"traceutil/trace.go:171","msg":"trace[170257568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"1.161145316s","start":"2026-04-16T00:31:59.013929Z","end":"2026-04-16T00:32:00.175074Z","steps":["trace[170257568] 'agreement among raft nodes before linearized reading' (duration: 1.161109905s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:00.175105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:31:59.013908Z","time spent":"1.161191757s","remote":"127.0.0.1:34974","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-16T00:32:00.175104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.589856235s","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-16T00:32:00.175152Z","caller":"traceutil/trace.go:171","msg":"trace[375181708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:500; }","duration":"1.589941086s","start":"2026-04-16T00:31:58.585198Z","end":"2026-04-16T00:32:00.175139Z","steps":["trace[375181708] 'agreement among raft nodes before linearized reading' (duration: 1.589830434s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:00.175185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:31:58.585181Z","time spent":"1.589997058s","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T00:32:00.175235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580261012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:32:00.175271Z","caller":"traceutil/trace.go:171","msg":"trace[1494350158] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:500; }","duration":"1.580328245s","start":"2026-04-16T00:31:58.594931Z","end":"2026-04-16T00:32:00.175259Z","steps":["trace[1494350158] 'agreement among raft nodes before linearized reading' (duration: 1.580271813s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:00.1753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:31:58.594906Z","time spent":"1.580387475s","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-16T00:32:00.997394Z","caller":"traceutil/trace.go:171","msg":"trace[2048115885] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"221.332206ms","start":"2026-04-16T00:32:00.776012Z","end":"2026-04-16T00:32:00.997344Z","steps":["trace[2048115885] 'process raft request' (duration: 140.376504ms)","trace[2048115885] 'compare' (duration: 80.739597ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:00.9977Z","caller":"traceutil/trace.go:171","msg":"trace[77888947] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"221.684542ms","start":"2026-04-16T00:32:00.776Z","end":"2026-04-16T00:32:00.997684Z","steps":["trace[77888947] 'process raft request' (duration: 221.517859ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:00.997928Z","caller":"traceutil/trace.go:171","msg":"trace[1367424989] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"215.332992ms","start":"2026-04-16T00:32:00.782589Z","end":"2026-04-16T00:32:00.997922Z","steps":["trace[1367424989] 'process raft request' (duration: 215.056697ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:01.027309Z","caller":"traceutil/trace.go:171","msg":"trace[1008489340] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"221.58161ms","start":"2026-04-16T00:32:00.805693Z","end":"2026-04-16T00:32:01.027275Z","steps":["trace[1008489340] 'process raft request' (duration: 221.448268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:01.452779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.352783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:32:01.452924Z","caller":"traceutil/trace.go:171","msg":"trace[843509075] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"382.897388ms","start":"2026-04-16T00:32:01.070007Z","end":"2026-04-16T00:32:01.452904Z","steps":["trace[843509075] 'process raft request' (duration: 200.278471ms)","trace[843509075] 'compare' (duration: 182.209789ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:32:01.452996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:01.06999Z","time spent":"382.974289ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:32:01.456981Z","caller":"traceutil/trace.go:171","msg":"trace[822810129] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"376.539228ms","start":"2026-04-16T00:32:01.080417Z","end":"2026-04-16T00:32:01.456956Z","steps":["trace[822810129] 'process raft request' (duration: 376.302944ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:01.457051Z","caller":"traceutil/trace.go:171","msg":"trace[1511083549] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"362.300479ms","start":"2026-04-16T00:32:01.094688Z","end":"2026-04-16T00:32:01.456989Z","steps":["trace[1511083549] 'process raft request' (duration: 362.180377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:01.457125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:01.080394Z","time spent":"376.655501ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:32:01.457228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:01.094661Z","time spent":"362.485133ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T00:32:01.615883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.014343ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-16T00:32:01.616284Z","caller":"traceutil/trace.go:171","msg":"trace[1159429194] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"154.71933ms","start":"2026-04-16T00:32:01.461545Z","end":"2026-04-16T00:32:01.616265Z","steps":["trace[1159429194] 'process raft request' (duration: 154.571998ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:01.633482Z","caller":"traceutil/trace.go:171","msg":"trace[1103438110] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"166.693197ms","start":"2026-04-16T00:32:01.46677Z","end":"2026-04-16T00:32:01.633463Z","steps":["trace[1103438110] 'process raft request' (duration: 166.513533ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:01.633673Z","caller":"traceutil/trace.go:171","msg":"trace[1909145926] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"129.569686ms","start":"2026-04-16T00:32:01.504098Z","end":"2026-04-16T00:32:01.633668Z","steps":["trace[1909145926] 'process raft request' (duration: 129.534695ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:01.633771Z","caller":"traceutil/trace.go:171","msg":"trace[1358951482] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"148.504234ms","start":"2026-04-16T00:32:01.485214Z","end":"2026-04-16T00:32:01.633718Z","steps":["trace[1358951482] 'process raft request' (duration: 148.163967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:02.462789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.053623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:32:02.462897Z","caller":"traceutil/trace.go:171","msg":"trace[1121514472] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"449.382938ms","start":"2026-04-16T00:32:02.013497Z","end":"2026-04-16T00:32:02.46288Z","steps":["trace[1121514472] 'read index received' (duration: 45.079389ms)","trace[1121514472] 'applied index is now lower than readState.Index' (duration: 404.302559ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:02.462963Z","caller":"traceutil/trace.go:171","msg":"trace[1208010968] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"469.537148ms","start":"2026-04-16T00:32:01.993416Z","end":"2026-04-16T00:32:02.462953Z","steps":["trace[1208010968] 'process raft request' (duration: 194.240121ms)","trace[1208010968] 'compare' (duration: 274.918931ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:32:02.463027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:01.993399Z","time spent":"469.59772ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:32:02.463238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.718095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:32:02.463329Z","caller":"traceutil/trace.go:171","msg":"trace[1023892864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"449.851837ms","start":"2026-04-16T00:32:02.013455Z","end":"2026-04-16T00:32:02.463307Z","steps":["trace[1023892864] 'agreement among raft nodes before linearized reading' (duration: 449.721034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:02.46337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:02.013435Z","time spent":"449.927298ms","remote":"127.0.0.1:34974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T00:32:02.591125Z","caller":"traceutil/trace.go:171","msg":"trace[1506244223] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"486.924705ms","start":"2026-04-16T00:32:02.10417Z","end":"2026-04-16T00:32:02.591094Z","steps":["trace[1506244223] 'process raft request' (duration: 478.530017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:02.591307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:02.104152Z","time spent":"487.055888ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:32:02.591411Z","caller":"traceutil/trace.go:171","msg":"trace[1505122703] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:586; }","duration":"128.472781ms","start":"2026-04-16T00:32:02.462922Z","end":"2026-04-16T00:32:02.591394Z","steps":["trace[1505122703] 'read index received' (duration: 119.773117ms)","trace[1505122703] 'applied index is now lower than readState.Index' (duration: 8.699124ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:32:02.591515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.48193ms","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-16T00:32:02.59154Z","caller":"traceutil/trace.go:171","msg":"trace[1802396578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"389.55566ms","start":"2026-04-16T00:32:02.201976Z","end":"2026-04-16T00:32:02.591531Z","steps":["trace[1802396578] 'agreement among raft nodes before linearized reading' (duration: 389.49348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:02.59152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.663914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-16T00:32:02.591525Z","caller":"traceutil/trace.go:171","msg":"trace[1350615281] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"469.979336ms","start":"2026-04-16T00:32:02.121522Z","end":"2026-04-16T00:32:02.591501Z","steps":["trace[1350615281] 'process raft request' (duration: 469.6737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:02.591564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:02.201958Z","time spent":"389.602251ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T00:32:02.591607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.700585ms","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-16T00:32:02.59162Z","caller":"traceutil/trace.go:171","msg":"trace[1795510274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"388.784846ms","start":"2026-04-16T00:32:02.202819Z","end":"2026-04-16T00:32:02.591603Z","steps":["trace[1795510274] 'agreement among raft nodes before linearized reading' (duration: 388.610322ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:02.59165Z","caller":"traceutil/trace.go:171","msg":"trace[100083510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"405.804196ms","start":"2026-04-16T00:32:02.185835Z","end":"2026-04-16T00:32:02.591639Z","steps":["trace[100083510] 'agreement among raft nodes before linearized reading' (duration: 405.677964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:02.591655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:02.202813Z","time spent":"388.835206ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T00:32:02.591655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:02.121503Z","time spent":"470.115588ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T00:32:02.59168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:02.185819Z","time spent":"405.855437ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T00:32:02.593347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.991881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:32:02.593419Z","caller":"traceutil/trace.go:171","msg":"trace[1776555523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"129.098712ms","start":"2026-04-16T00:32:02.464304Z","end":"2026-04-16T00:32:02.593403Z","steps":["trace[1776555523] 'agreement among raft nodes before linearized reading' (duration: 128.96538ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:02.593639Z","caller":"traceutil/trace.go:171","msg":"trace[1557895794] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"113.57462ms","start":"2026-04-16T00:32:02.480054Z","end":"2026-04-16T00:32:02.593628Z","steps":["trace[1557895794] 'process raft request' (duration: 113.112791ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:02.758637Z","caller":"traceutil/trace.go:171","msg":"trace[377667378] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"135.441803ms","start":"2026-04-16T00:32:02.623174Z","end":"2026-04-16T00:32:02.758615Z","steps":["trace[377667378] 'process raft request' (duration: 69.186493ms)","trace[377667378] 'compare' (duration: 66.035416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:32:04.935767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.480201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:32:04.936275Z","caller":"traceutil/trace.go:171","msg":"trace[1103399029] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"275.216999ms","start":"2026-04-16T00:32:04.661017Z","end":"2026-04-16T00:32:04.936234Z","steps":["trace[1103399029] 'process raft request' (duration: 104.164417ms)","trace[1103399029] 'compare' (duration: 170.344678ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:07.351164Z","caller":"traceutil/trace.go:171","msg":"trace[1962041297] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"411.290714ms","start":"2026-04-16T00:32:06.93984Z","end":"2026-04-16T00:32:07.351131Z","steps":["trace[1962041297] 'read index received' (duration: 410.258814ms)","trace[1962041297] 'applied index is now lower than readState.Index' (duration: 1.03096ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:07.351228Z","caller":"traceutil/trace.go:171","msg":"trace[2140598618] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"427.362965ms","start":"2026-04-16T00:32:06.923843Z","end":"2026-04-16T00:32:07.351206Z","steps":["trace[2140598618] 'process raft request' (duration: 426.741294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:07.351386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.543488ms","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-16T00:32:07.351384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:06.923809Z","time spent":"427.476028ms","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:32:07.351424Z","caller":"traceutil/trace.go:171","msg":"trace[770513188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"411.646181ms","start":"2026-04-16T00:32:06.939767Z","end":"2026-04-16T00:32:07.351413Z","steps":["trace[770513188] 'agreement among raft nodes before linearized reading' (duration: 411.511329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:07.35145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:06.939739Z","time spent":"411.704922ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T00:32:07.351505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.193667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-28sgc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-16T00:32:07.351544Z","caller":"traceutil/trace.go:171","msg":"trace[1326270389] range","detail":"{range_begin:/registry/pods/kube-system/cilium-28sgc; range_end:; response_count:1; response_revision:605; }","duration":"172.281178ms","start":"2026-04-16T00:32:07.179251Z","end":"2026-04-16T00:32:07.351532Z","steps":["trace[1326270389] 'agreement among raft nodes before linearized reading' (duration: 172.171806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:07.351838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.232119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:32:07.351908Z","caller":"traceutil/trace.go:171","msg":"trace[437093328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"337.81904ms","start":"2026-04-16T00:32:07.014078Z","end":"2026-04-16T00:32:07.351897Z","steps":["trace[437093328] 'agreement among raft nodes before linearized reading' (duration: 337.2893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:07.351941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:07.014051Z","time spent":"337.882551ms","remote":"127.0.0.1:34974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T00:32:07.934877Z","caller":"traceutil/trace.go:171","msg":"trace[126805439] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"576.57217ms","start":"2026-04-16T00:32:07.358265Z","end":"2026-04-16T00:32:07.934837Z","steps":["trace[126805439] 'process raft request' (duration: 562.069279ms)","trace[126805439] 'compare' (duration: 14.321827ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:32:07.935101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:07.357978Z","time spent":"577.020699ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:32:08.021673Z","caller":"traceutil/trace.go:171","msg":"trace[199263134] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"657.164175ms","start":"2026-04-16T00:32:07.364477Z","end":"2026-04-16T00:32:08.021641Z","steps":["trace[199263134] 'process raft request' (duration: 656.771387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:08.021861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:07.364462Z","time spent":"657.287598ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:32:08.021913Z","caller":"traceutil/trace.go:171","msg":"trace[1716214866] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:626; }","duration":"380.253202ms","start":"2026-04-16T00:32:07.641621Z","end":"2026-04-16T00:32:08.021874Z","steps":["trace[1716214866] 'read index received' (duration: 278.842368ms)","trace[1716214866] 'applied index is now lower than readState.Index' (duration: 101.409124ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:08.021983Z","caller":"traceutil/trace.go:171","msg":"trace[1504696396] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"617.501124ms","start":"2026-04-16T00:32:07.404472Z","end":"2026-04-16T00:32:08.021973Z","steps":["trace[1504696396] 'process raft request' (duration: 616.969304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:08.022026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:07.404443Z","time spent":"617.563444ms","remote":"127.0.0.1:35330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T00:32:08.022257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.617958ms","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-16T00:32:08.022308Z","caller":"traceutil/trace.go:171","msg":"trace[402909415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"380.740202ms","start":"2026-04-16T00:32:07.641555Z","end":"2026-04-16T00:32:08.022295Z","steps":["trace[402909415] 'agreement among raft nodes before linearized reading' (duration: 380.450526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:08.022347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:07.641524Z","time spent":"380.813473ms","remote":"127.0.0.1:35246","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-16T00:32:08.29935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.557735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:32:08.29949Z","caller":"traceutil/trace.go:171","msg":"trace[1957994357] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"265.790946ms","start":"2026-04-16T00:32:08.033678Z","end":"2026-04-16T00:32:08.299469Z","steps":["trace[1957994357] 'read index received' (duration: 16.953506ms)","trace[1957994357] 'applied index is now lower than readState.Index' (duration: 248.83616ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:08.299555Z","caller":"traceutil/trace.go:171","msg":"trace[386613837] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"272.99597ms","start":"2026-04-16T00:32:08.02651Z","end":"2026-04-16T00:32:08.299506Z","steps":["trace[386613837] 'process raft request' (duration: 24.183021ms)","trace[386613837] 'compare' (duration: 248.379441ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:32:08.29964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.929328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:32:08.299731Z","caller":"traceutil/trace.go:171","msg":"trace[1909980805] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:609; }","duration":"266.092032ms","start":"2026-04-16T00:32:08.033625Z","end":"2026-04-16T00:32:08.299717Z","steps":["trace[1909980805] 'agreement among raft nodes before linearized reading' (duration: 265.94494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:08.821968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.639305ms","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-16T00:32:08.822073Z","caller":"traceutil/trace.go:171","msg":"trace[1924326614] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"168.826909ms","start":"2026-04-16T00:32:08.65322Z","end":"2026-04-16T00:32:08.822047Z","steps":["trace[1924326614] 'range keys from in-memory index tree' (duration: 168.391979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:08.82204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.423962ms","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-16T00:32:08.822177Z","caller":"traceutil/trace.go:171","msg":"trace[129755668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"182.608266ms","start":"2026-04-16T00:32:08.639545Z","end":"2026-04-16T00:32:08.822153Z","steps":["trace[129755668] 'range keys from in-memory index tree' (duration: 182.214238ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:09.29193Z","caller":"traceutil/trace.go:171","msg":"trace[781914400] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"600.847486ms","start":"2026-04-16T00:32:08.691051Z","end":"2026-04-16T00:32:09.291899Z","steps":["trace[781914400] 'process raft request' (duration: 599.747106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:09.292162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:08.691013Z","time spent":"601.05672ms","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:32:09.31007Z","caller":"traceutil/trace.go:171","msg":"trace[10840167] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"528.084218ms","start":"2026-04-16T00:32:08.781949Z","end":"2026-04-16T00:32:09.310033Z","steps":["trace[10840167] 'process raft request' (duration: 527.810093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:09.310272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:08.781914Z","time spent":"528.257782ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:32:09.310533Z","caller":"traceutil/trace.go:171","msg":"trace[160444087] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"483.807273ms","start":"2026-04-16T00:32:08.826701Z","end":"2026-04-16T00:32:09.310508Z","steps":["trace[160444087] 'process raft request' (duration: 483.274513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:09.310663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:08.826669Z","time spent":"483.928274ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:32:09.311048Z","caller":"traceutil/trace.go:171","msg":"trace[1615679132] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:630; }","duration":"297.02524ms","start":"2026-04-16T00:32:09.013983Z","end":"2026-04-16T00:32:09.311008Z","steps":["trace[1615679132] 'read index received' (duration: 276.704452ms)","trace[1615679132] 'applied index is now lower than readState.Index' (duration: 20.319788ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:32:09.311192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.171043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:32:09.311215Z","caller":"traceutil/trace.go:171","msg":"trace[1566499666] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"484.1845ms","start":"2026-04-16T00:32:08.827013Z","end":"2026-04-16T00:32:09.311198Z","steps":["trace[1566499666] 'process raft request' (duration: 483.451396ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:09.311219Z","caller":"traceutil/trace.go:171","msg":"trace[455391675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"297.291285ms","start":"2026-04-16T00:32:09.013918Z","end":"2026-04-16T00:32:09.31121Z","steps":["trace[455391675] 'agreement among raft nodes before linearized reading' (duration: 297.185523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:09.31129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:08.826983Z","time spent":"484.270011ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:32:10.749357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.504923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:32:10.749677Z","caller":"traceutil/trace.go:171","msg":"trace[1864808461] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"194.570897ms","start":"2026-04-16T00:32:10.555061Z","end":"2026-04-16T00:32:10.749632Z","steps":["trace[1864808461] 'process raft request' (duration: 194.469955ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:10.749786Z","caller":"traceutil/trace.go:171","msg":"trace[1553467159] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"436.92588ms","start":"2026-04-16T00:32:10.312778Z","end":"2026-04-16T00:32:10.749704Z","steps":["trace[1553467159] 'process raft request' (duration: 105.950699ms)","trace[1553467159] 'compare' (duration: 330.227507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:32:10.75018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:10.312758Z","time spent":"437.256307ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T00:32:17.460528Z","caller":"traceutil/trace.go:171","msg":"trace[1679759383] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"128.479062ms","start":"2026-04-16T00:32:17.332016Z","end":"2026-04-16T00:32:17.460495Z","steps":["trace[1679759383] 'read index received' (duration: 127.608717ms)","trace[1679759383] 'applied index is now lower than readState.Index' (duration: 869.015µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:17.460598Z","caller":"traceutil/trace.go:171","msg":"trace[158372517] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"159.619915ms","start":"2026-04-16T00:32:17.300935Z","end":"2026-04-16T00:32:17.460555Z","steps":["trace[158372517] 'process raft request' (duration: 158.878041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:17.460725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.673976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1712"} {"level":"info","ts":"2026-04-16T00:32:17.460789Z","caller":"traceutil/trace.go:171","msg":"trace[749912852] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:723; }","duration":"128.839409ms","start":"2026-04-16T00:32:17.331933Z","end":"2026-04-16T00:32:17.460772Z","steps":["trace[749912852] 'agreement among raft nodes before linearized reading' (duration: 128.693716ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:22.368028Z","caller":"traceutil/trace.go:171","msg":"trace[1425240882] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"364.593956ms","start":"2026-04-16T00:32:22.003407Z","end":"2026-04-16T00:32:22.368001Z","steps":["trace[1425240882] 'process raft request' (duration: 363.976075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:22.368192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:22.003382Z","time spent":"364.723299ms","remote":"127.0.0.1:35246","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-16T00:32:26.049242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04000959s","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-16T00:32:26.049392Z","caller":"traceutil/trace.go:171","msg":"trace[5680638] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:775; }","duration":"1.040173243s","start":"2026-04-16T00:32:25.009196Z","end":"2026-04-16T00:32:26.049369Z","steps":["trace[5680638] 'range keys from in-memory index tree' (duration: 1.03998643s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:26.049647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037150158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T00:32:26.049655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.730952ms","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-16T00:32:26.049753Z","caller":"traceutil/trace.go:171","msg":"trace[412137830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"1.037319621s","start":"2026-04-16T00:32:25.012409Z","end":"2026-04-16T00:32:26.049729Z","steps":["trace[412137830] 'range keys from in-memory index tree' (duration: 1.037056116s)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:26.049761Z","caller":"traceutil/trace.go:171","msg":"trace[1278917890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"997.893704ms","start":"2026-04-16T00:32:25.051845Z","end":"2026-04-16T00:32:26.049739Z","steps":["trace[1278917890] 'range keys from in-memory index tree' (duration: 997.482386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:26.049802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:25.01238Z","time spent":"1.037412702s","remote":"127.0.0.1:34974","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-16T00:32:26.049812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:25.051825Z","time spent":"997.974935ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T00:32:26.049878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.873017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:32:26.049941Z","caller":"traceutil/trace.go:171","msg":"trace[997019027] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"762.488033ms","start":"2026-04-16T00:32:25.287443Z","end":"2026-04-16T00:32:26.049931Z","steps":["trace[997019027] 'read index received' (duration: 470.399753ms)","trace[997019027] 'applied index is now lower than readState.Index' (duration: 292.08723ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:26.05003Z","caller":"traceutil/trace.go:171","msg":"trace[2001418564] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"950.222788ms","start":"2026-04-16T00:32:25.099797Z","end":"2026-04-16T00:32:26.05002Z","steps":["trace[2001418564] 'process raft request' (duration: 658.136258ms)","trace[2001418564] 'compare' (duration: 291.461019ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:32:26.050083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:25.09977Z","time spent":"950.284849ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:32:26.050194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.120939ms","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-16T00:32:26.05023Z","caller":"traceutil/trace.go:171","msg":"trace[1637481356] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:776; }","duration":"519.203241ms","start":"2026-04-16T00:32:25.531014Z","end":"2026-04-16T00:32:26.050218Z","steps":["trace[1637481356] 'agreement among raft nodes before linearized reading' (duration: 519.107829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:26.050257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:25.530987Z","time spent":"519.263991ms","remote":"127.0.0.1:35246","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-16T00:32:26.050316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.88343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g674r\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-16T00:32:26.050345Z","caller":"traceutil/trace.go:171","msg":"trace[672977666] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g674r; range_end:; response_count:1; response_revision:776; }","duration":"762.958313ms","start":"2026-04-16T00:32:25.287376Z","end":"2026-04-16T00:32:26.050335Z","steps":["trace[672977666] 'agreement among raft nodes before linearized reading' (duration: 762.819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:26.050373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:25.287348Z","time spent":"763.018543ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g674r\" "} {"level":"warn","ts":"2026-04-16T00:32:26.050501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.696575ms","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-16T00:32:26.050596Z","caller":"traceutil/trace.go:171","msg":"trace[1332771906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:776; }","duration":"494.850997ms","start":"2026-04-16T00:32:25.555719Z","end":"2026-04-16T00:32:26.05057Z","steps":["trace[1332771906] 'agreement among raft nodes before linearized reading' (duration: 494.601963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:26.050667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:25.555691Z","time spent":"494.958499ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T00:32:29.26544Z","caller":"traceutil/trace.go:171","msg":"trace[189590675] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"156.056395ms","start":"2026-04-16T00:32:29.109352Z","end":"2026-04-16T00:32:29.265409Z","steps":["trace[189590675] 'process raft request' (duration: 155.865341ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:29.563091Z","caller":"traceutil/trace.go:171","msg":"trace[778960586] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"273.312623ms","start":"2026-04-16T00:32:29.289746Z","end":"2026-04-16T00:32:29.563059Z","steps":["trace[778960586] 'process raft request' (duration: 259.883471ms)","trace[778960586] 'compare' (duration: 13.29695ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:30.391515Z","caller":"traceutil/trace.go:171","msg":"trace[1905361061] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"203.306673ms","start":"2026-04-16T00:32:30.188175Z","end":"2026-04-16T00:32:30.391482Z","steps":["trace[1905361061] 'process raft request' (duration: 202.58874ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:31.364747Z","caller":"traceutil/trace.go:171","msg":"trace[1937279117] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:857; }","duration":"113.120471ms","start":"2026-04-16T00:32:31.2516Z","end":"2026-04-16T00:32:31.36472Z","steps":["trace[1937279117] 'read index received' (duration: 108.964066ms)","trace[1937279117] 'applied index is now lower than readState.Index' (duration: 4.155355ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:31.365003Z","caller":"traceutil/trace.go:171","msg":"trace[1258716571] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"133.977397ms","start":"2026-04-16T00:32:31.230982Z","end":"2026-04-16T00:32:31.36496Z","steps":["trace[1258716571] 'process raft request' (duration: 129.6411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:31.365063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.439577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"info","ts":"2026-04-16T00:32:31.365127Z","caller":"traceutil/trace.go:171","msg":"trace[285307506] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:832; }","duration":"113.570929ms","start":"2026-04-16T00:32:31.251543Z","end":"2026-04-16T00:32:31.365114Z","steps":["trace[285307506] 'agreement among raft nodes before linearized reading' (duration: 113.352465ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:41.510303Z","caller":"traceutil/trace.go:171","msg":"trace[1653315525] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"144.459628ms","start":"2026-04-16T00:32:41.365827Z","end":"2026-04-16T00:32:41.510287Z","steps":["trace[1653315525] 'process raft request' (duration: 144.326456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:41.673371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.178285ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-16T00:32:41.673753Z","caller":"traceutil/trace.go:171","msg":"trace[2104301362] transaction","detail":"{read_only:false; number_of_response:1; response_revision:955; }","duration":"161.075025ms","start":"2026-04-16T00:32:41.512657Z","end":"2026-04-16T00:32:41.673732Z","steps":["trace[2104301362] 'process raft request' (duration: 160.825061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:41.728272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.143096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:32:41.728362Z","caller":"traceutil/trace.go:171","msg":"trace[1319277179] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:955; }","duration":"151.28872ms","start":"2026-04-16T00:32:41.577052Z","end":"2026-04-16T00:32:41.728341Z","steps":["trace[1319277179] 'agreement among raft nodes before linearized reading' (duration: 96.683276ms)","trace[1319277179] 'count revisions from in-memory index tree' (duration: 54.462642ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:50.586888Z","caller":"traceutil/trace.go:171","msg":"trace[155916520] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"314.346371ms","start":"2026-04-16T00:32:50.272518Z","end":"2026-04-16T00:32:50.586864Z","steps":["trace[155916520] 'process raft request' (duration: 314.139767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:50.587031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:50.272495Z","time spent":"314.462953ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:32:50.613147Z","caller":"traceutil/trace.go:171","msg":"trace[104574490] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"340.319101ms","start":"2026-04-16T00:32:50.272806Z","end":"2026-04-16T00:32:50.613125Z","steps":["trace[104574490] 'process raft request' (duration: 340.136907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:50.613295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:50.272787Z","time spent":"340.427543ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:32:50.723188Z","caller":"traceutil/trace.go:171","msg":"trace[1738787969] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"105.550757ms","start":"2026-04-16T00:32:50.617579Z","end":"2026-04-16T00:32:50.72313Z","steps":["trace[1738787969] 'process raft request' (duration: 51.832877ms)","trace[1738787969] 'compare' (duration: 52.849725ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:50.723325Z","caller":"traceutil/trace.go:171","msg":"trace[195000784] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"104.622711ms","start":"2026-04-16T00:32:50.618675Z","end":"2026-04-16T00:32:50.723298Z","steps":["trace[195000784] 'process raft request' (duration: 103.727954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:51.83176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.631896ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-16T00:32:51.832072Z","caller":"traceutil/trace.go:171","msg":"trace[1946679605] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"292.374938ms","start":"2026-04-16T00:32:51.53968Z","end":"2026-04-16T00:32:51.832055Z","steps":["trace[1946679605] 'process raft request' (duration: 292.195614ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:55.994489Z","caller":"traceutil/trace.go:171","msg":"trace[2121500174] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"151.039921ms","start":"2026-04-16T00:32:55.84343Z","end":"2026-04-16T00:32:55.994469Z","steps":["trace[2121500174] 'process raft request' (duration: 150.94111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:32:59.665086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:32:59.665496Z","caller":"traceutil/trace.go:171","msg":"trace[798767326] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"178.671517ms","start":"2026-04-16T00:32:59.486795Z","end":"2026-04-16T00:32:59.665466Z","steps":["trace[798767326] 'process raft request' (duration: 54.076599ms)","trace[798767326] 'compare' (duration: 123.984887ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:32:59.665562Z","caller":"traceutil/trace.go:171","msg":"trace[1502070722] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"173.794501ms","start":"2026-04-16T00:32:59.491744Z","end":"2026-04-16T00:32:59.665539Z","steps":["trace[1502070722] 'process raft request' (duration: 173.72238ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:59.665595Z","caller":"traceutil/trace.go:171","msg":"trace[1001102814] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"174.643957ms","start":"2026-04-16T00:32:59.490917Z","end":"2026-04-16T00:32:59.665561Z","steps":["trace[1001102814] 'process raft request' (duration: 174.28207ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:32:59.883074Z","caller":"traceutil/trace.go:171","msg":"trace[782880541] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"211.491143ms","start":"2026-04-16T00:32:59.671553Z","end":"2026-04-16T00:32:59.883044Z","steps":["trace[782880541] 'process raft request' (duration: 211.091557ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:00.112256Z","caller":"traceutil/trace.go:171","msg":"trace[1649743673] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"436.789987ms","start":"2026-04-16T00:32:59.675425Z","end":"2026-04-16T00:33:00.112215Z","steps":["trace[1649743673] 'process raft request' (duration: 377.856423ms)","trace[1649743673] 'compare' (duration: 58.69567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:33:00.112419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.823579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0ad08344-cb93-42d2-a9f8-1f1a379bdb99\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-04-16T00:33:00.112383Z","caller":"traceutil/trace.go:171","msg":"trace[926569406] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"107.880622ms","start":"2026-04-16T00:33:00.00447Z","end":"2026-04-16T00:33:00.112351Z","steps":["trace[926569406] 'process raft request' (duration: 107.657968ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:00.112508Z","caller":"traceutil/trace.go:171","msg":"trace[1412152797] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0ad08344-cb93-42d2-a9f8-1f1a379bdb99; range_end:; response_count:1; response_revision:1034; }","duration":"431.941372ms","start":"2026-04-16T00:32:59.680555Z","end":"2026-04-16T00:33:00.112496Z","steps":["trace[1412152797] 'agreement among raft nodes before linearized reading' (duration: 431.774779ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:00.112209Z","caller":"traceutil/trace.go:171","msg":"trace[1358987388] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1065; }","duration":"431.615215ms","start":"2026-04-16T00:32:59.680568Z","end":"2026-04-16T00:33:00.112184Z","steps":["trace[1358987388] 'read index received' (duration: 202.055697ms)","trace[1358987388] 'applied index is now lower than readState.Index' (duration: 229.558518ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:33:00.112651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:59.680548Z","time spent":"432.051904ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3602,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0ad08344-cb93-42d2-a9f8-1f1a379bdb99\" "} {"level":"warn","ts":"2026-04-16T00:33:00.112671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:32:59.675408Z","time spent":"436.938209ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3564,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:33:00.112789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.325846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-16T00:33:00.112825Z","caller":"traceutil/trace.go:171","msg":"trace[191303628] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:1034; }","duration":"191.395779ms","start":"2026-04-16T00:32:59.921419Z","end":"2026-04-16T00:33:00.112815Z","steps":["trace[191303628] 'agreement among raft nodes before linearized reading' (duration: 191.267756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:00.112943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.191997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:33:00.112977Z","caller":"traceutil/trace.go:171","msg":"trace[1686510854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"100.248209ms","start":"2026-04-16T00:33:00.01272Z","end":"2026-04-16T00:33:00.112968Z","steps":["trace[1686510854] 'agreement among raft nodes before linearized reading' (duration: 100.111757ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:02.941202Z","caller":"traceutil/trace.go:171","msg":"trace[1685306245] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1082; }","duration":"447.962556ms","start":"2026-04-16T00:33:02.493214Z","end":"2026-04-16T00:33:02.941177Z","steps":["trace[1685306245] 'read index received' (duration: 447.829994ms)","trace[1685306245] 'applied index is now lower than readState.Index' (duration: 131.502µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:33:02.941349Z","caller":"traceutil/trace.go:171","msg":"trace[669496623] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"509.711677ms","start":"2026-04-16T00:33:02.431568Z","end":"2026-04-16T00:33:02.941279Z","steps":["trace[669496623] 'process raft request' (duration: 509.452092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:02.941445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.21514ms","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-16T00:33:02.941487Z","caller":"traceutil/trace.go:171","msg":"trace[1935944161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1048; }","duration":"448.312583ms","start":"2026-04-16T00:33:02.493161Z","end":"2026-04-16T00:33:02.941474Z","steps":["trace[1935944161] 'agreement among raft nodes before linearized reading' (duration: 448.15321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:02.941548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:33:02.493141Z","time spent":"448.396943ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T00:33:02.941572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.271659ms","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-16T00:33:02.941624Z","caller":"traceutil/trace.go:171","msg":"trace[2119647874] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1048; }","duration":"296.370391ms","start":"2026-04-16T00:33:02.645241Z","end":"2026-04-16T00:33:02.941612Z","steps":["trace[2119647874] 'agreement among raft nodes before linearized reading' (duration: 296.276119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:02.941655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:33:02.43155Z","time spent":"509.944131ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3681,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:33:02.941683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.034892ms","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-16T00:33:02.941771Z","caller":"traceutil/trace.go:171","msg":"trace[1853261101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1048; }","duration":"124.163294ms","start":"2026-04-16T00:33:02.817584Z","end":"2026-04-16T00:33:02.941748Z","steps":["trace[1853261101] 'agreement among raft nodes before linearized reading' (duration: 123.93277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:02.941827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.696457ms","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-16T00:33:02.941893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.195242ms","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-16T00:33:02.941916Z","caller":"traceutil/trace.go:171","msg":"trace[1805897019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1048; }","duration":"163.803298ms","start":"2026-04-16T00:33:02.77809Z","end":"2026-04-16T00:33:02.941894Z","steps":["trace[1805897019] 'agreement among raft nodes before linearized reading' (duration: 163.500814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:02.941977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.314847ms","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-16T00:33:02.942013Z","caller":"traceutil/trace.go:171","msg":"trace[2015549583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1048; }","duration":"124.349747ms","start":"2026-04-16T00:33:02.817653Z","end":"2026-04-16T00:33:02.942003Z","steps":["trace[2015549583] 'agreement among raft nodes before linearized reading' (duration: 124.252016ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:02.941927Z","caller":"traceutil/trace.go:171","msg":"trace[1544911963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1048; }","duration":"246.267214ms","start":"2026-04-16T00:33:02.69565Z","end":"2026-04-16T00:33:02.941917Z","steps":["trace[1544911963] 'agreement among raft nodes before linearized reading' (duration: 246.171662ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:07.070898Z","caller":"traceutil/trace.go:171","msg":"trace[123913399] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"102.319446ms","start":"2026-04-16T00:33:06.968553Z","end":"2026-04-16T00:33:07.070872Z","steps":["trace[123913399] 'process raft request' (duration: 102.123264ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:12.74365Z","caller":"traceutil/trace.go:171","msg":"trace[623546807] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"134.136842ms","start":"2026-04-16T00:33:12.609465Z","end":"2026-04-16T00:33:12.743602Z","steps":["trace[623546807] 'process raft request' (duration: 133.902138ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:13.241122Z","caller":"traceutil/trace.go:171","msg":"trace[1750605408] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"131.114869ms","start":"2026-04-16T00:33:13.109988Z","end":"2026-04-16T00:33:13.241103Z","steps":["trace[1750605408] 'read index received' (duration: 65.706962ms)","trace[1750605408] 'applied index is now lower than readState.Index' (duration: 65.407137ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:33:13.241224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.259661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:33:13.241245Z","caller":"traceutil/trace.go:171","msg":"trace[693266460] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1101; }","duration":"131.354253ms","start":"2026-04-16T00:33:13.109884Z","end":"2026-04-16T00:33:13.241239Z","steps":["trace[693266460] 'agreement among raft nodes before linearized reading' (duration: 131.297192ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:13.241216Z","caller":"traceutil/trace.go:171","msg":"trace[1608157569] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"140.125016ms","start":"2026-04-16T00:33:13.101052Z","end":"2026-04-16T00:33:13.241177Z","steps":["trace[1608157569] 'process raft request' (duration: 74.72746ms)","trace[1608157569] 'compare' (duration: 65.223724ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:33:13.247321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.388259ms","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-16T00:33:13.247427Z","caller":"traceutil/trace.go:171","msg":"trace[503870469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1102; }","duration":"102.49634ms","start":"2026-04-16T00:33:13.144918Z","end":"2026-04-16T00:33:13.247414Z","steps":["trace[503870469] 'agreement among raft nodes before linearized reading' (duration: 102.292307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:13.247319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.792471ms","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-16T00:33:13.24754Z","caller":"traceutil/trace.go:171","msg":"trace[461261350] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"105.078525ms","start":"2026-04-16T00:33:13.142446Z","end":"2026-04-16T00:33:13.247525Z","steps":["trace[461261350] 'agreement among raft nodes before linearized reading' (duration: 104.721759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:13.247374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.278958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-16T00:33:13.247338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.690855ms","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-16T00:33:13.247684Z","caller":"traceutil/trace.go:171","msg":"trace[1747852699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1102; }","duration":"103.04158ms","start":"2026-04-16T00:33:13.14461Z","end":"2026-04-16T00:33:13.247651Z","steps":["trace[1747852699] 'agreement among raft nodes before linearized reading' (duration: 102.636844ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:13.247724Z","caller":"traceutil/trace.go:171","msg":"trace[1192390531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"102.587952ms","start":"2026-04-16T00:33:13.145042Z","end":"2026-04-16T00:33:13.24763Z","steps":["trace[1192390531] 'agreement among raft nodes before linearized reading' (duration: 102.142155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:13.480542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.919377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:33:13.480673Z","caller":"traceutil/trace.go:171","msg":"trace[1554202209] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"228.592464ms","start":"2026-04-16T00:33:13.252056Z","end":"2026-04-16T00:33:13.480649Z","steps":["trace[1554202209] 'process raft request' (duration: 113.478453ms)","trace[1554202209] 'compare' (duration: 114.791895ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:33:13.480908Z","caller":"traceutil/trace.go:171","msg":"trace[197786591] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"228.705125ms","start":"2026-04-16T00:33:13.252182Z","end":"2026-04-16T00:33:13.480887Z","steps":["trace[197786591] 'process raft request' (duration: 228.569762ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:13.481149Z","caller":"traceutil/trace.go:171","msg":"trace[15402482] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"228.743956ms","start":"2026-04-16T00:33:13.252395Z","end":"2026-04-16T00:33:13.481139Z","steps":["trace[15402482] 'process raft request' (duration: 228.655304ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:13.481173Z","caller":"traceutil/trace.go:171","msg":"trace[836389775] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"228.869189ms","start":"2026-04-16T00:33:13.252272Z","end":"2026-04-16T00:33:13.481141Z","steps":["trace[836389775] 'process raft request' (duration: 228.582943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:16.824562Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605174813730100,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T00:33:16.869265Z","caller":"traceutil/trace.go:171","msg":"trace[1747178473] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"545.621415ms","start":"2026-04-16T00:33:16.323619Z","end":"2026-04-16T00:33:16.869241Z","steps":["trace[1747178473] 'read index received' (duration: 545.401912ms)","trace[1747178473] 'applied index is now lower than readState.Index' (duration: 218.173µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:33:16.869323Z","caller":"traceutil/trace.go:171","msg":"trace[1810638036] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"587.31171ms","start":"2026-04-16T00:33:16.28198Z","end":"2026-04-16T00:33:16.869291Z","steps":["trace[1810638036] 'process raft request' (duration: 587.134446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:16.869437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.757943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:33:16.869476Z","caller":"traceutil/trace.go:171","msg":"trace[1655127788] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1116; }","duration":"483.836034ms","start":"2026-04-16T00:33:16.385629Z","end":"2026-04-16T00:33:16.869465Z","steps":["trace[1655127788] 'agreement among raft nodes before linearized reading' (duration: 483.769074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:16.869476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:33:16.281962Z","time spent":"587.433882ms","remote":"127.0.0.1:35246","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-16T00:33:16.8695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:33:16.385609Z","time spent":"483.884294ms","remote":"127.0.0.1:38798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-16T00:33:16.869503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.859549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-16T00:33:16.869563Z","caller":"traceutil/trace.go:171","msg":"trace[126339639] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:1116; }","duration":"545.960043ms","start":"2026-04-16T00:33:16.323587Z","end":"2026-04-16T00:33:16.869547Z","steps":["trace[126339639] 'agreement among raft nodes before linearized reading' (duration: 545.778289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:16.869606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:33:16.323571Z","time spent":"546.023903ms","remote":"127.0.0.1:35008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.140\" "} {"level":"info","ts":"2026-04-16T00:33:21.01541Z","caller":"traceutil/trace.go:171","msg":"trace[1418619335] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"131.055208ms","start":"2026-04-16T00:33:20.884211Z","end":"2026-04-16T00:33:21.015266Z","steps":["trace[1418619335] 'process raft request' (duration: 130.777533ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:23.655155Z","caller":"traceutil/trace.go:171","msg":"trace[1789672297] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"125.813453ms","start":"2026-04-16T00:33:23.529306Z","end":"2026-04-16T00:33:23.655119Z","steps":["trace[1789672297] 'process raft request' (duration: 124.455759ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:23.655185Z","caller":"traceutil/trace.go:171","msg":"trace[1821802085] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"125.619919ms","start":"2026-04-16T00:33:23.529539Z","end":"2026-04-16T00:33:23.655159Z","steps":["trace[1821802085] 'process raft request' (duration: 125.494537ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:23.655397Z","caller":"traceutil/trace.go:171","msg":"trace[784445117] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"125.434306ms","start":"2026-04-16T00:33:23.529952Z","end":"2026-04-16T00:33:23.655387Z","steps":["trace[784445117] 'process raft request' (duration: 125.120092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:35.73203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.490866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T00:33:35.732081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.760964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:1914"} {"level":"info","ts":"2026-04-16T00:33:35.732131Z","caller":"traceutil/trace.go:171","msg":"trace[773639894] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-node-lease/; range_end:/registry/persistentvolumeclaims/kube-node-lease0; response_count:0; response_revision:1181; }","duration":"180.61441ms","start":"2026-04-16T00:33:35.551494Z","end":"2026-04-16T00:33:35.732109Z","steps":["trace[773639894] 'range keys from in-memory index tree' (duration: 180.372113ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:33:35.732161Z","caller":"traceutil/trace.go:171","msg":"trace[79913484] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:1181; }","duration":"177.868299ms","start":"2026-04-16T00:33:35.554266Z","end":"2026-04-16T00:33:35.732134Z","steps":["trace[79913484] 'range keys from in-memory index tree' (duration: 177.59347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:33:35.732354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.600188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-public/\" range_end:\"/registry/pods/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:33:35.732416Z","caller":"traceutil/trace.go:171","msg":"trace[1609408354] range","detail":"{range_begin:/registry/pods/kube-public/; range_end:/registry/pods/kube-public0; response_count:0; response_revision:1181; }","duration":"154.709221ms","start":"2026-04-16T00:33:35.577694Z","end":"2026-04-16T00:33:35.732403Z","steps":["trace[1609408354] 'range keys from in-memory index tree' (duration: 154.526316ms)"],"step_count":1}