{"level":"warn","ts":"2026-04-23T12:25:51.636338Z","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-23T12:25:51.636447Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.90:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.90:2380","--initial-cluster=instance=https://199.19.213.90:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.90:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.90: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-23T12:25:51.63654Z","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-23T12:25:51.636559Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-04-23T12:25:51.636587Z","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-23T12:25:51.638578Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"]} {"level":"info","ts":"2026-04-23T12:25:51.638881Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.90: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-23T12:25:51.645722Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.270412ms"} {"level":"info","ts":"2026-04-23T12:25:51.660623Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b21fdc9c735cc27","cluster-id":"77163f3b44f8f1db"} {"level":"info","ts":"2026-04-23T12:25:51.660756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T12:25:51.660782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 0"} {"level":"info","ts":"2026-04-23T12:25:51.660797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b21fdc9c735cc27 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T12:25:51.660832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 1"} {"level":"info","ts":"2026-04-23T12:25:51.660874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"warn","ts":"2026-04-23T12:25:51.672683Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T12:25:51.676213Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T12:25:51.677945Z","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-23T12:25:51.680755Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b21fdc9c735cc27","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T12:25:51.680927Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b21fdc9c735cc27","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T12:25:51.681071Z","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-23T12:25:51.681124Z","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-23T12:25:51.681137Z","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-23T12:25:51.682171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"info","ts":"2026-04-23T12:25:51.682299Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","added-peer-id":"2b21fdc9c735cc27","added-peer-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-04-23T12:25:51.682886Z","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-23T12:25:51.683101Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b21fdc9c735cc27","initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T12:25:51.683134Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T12:25:51.683691Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-04-23T12:25:51.683712Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-04-23T12:25:51.761457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T12:25:51.761526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T12:25:51.761569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgPreVoteResp from 2b21fdc9c735cc27 at term 1"} {"level":"info","ts":"2026-04-23T12:25:51.761591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became candidate at term 2"} {"level":"info","ts":"2026-04-23T12:25:51.761603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgVoteResp from 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-04-23T12:25:51.761618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became leader at term 2"} {"level":"info","ts":"2026-04-23T12:25:51.761633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b21fdc9c735cc27 elected leader 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-04-23T12:25:51.762596Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T12:25:51.763481Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T12:25:51.763693Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T12:25:51.763731Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T12:25:51.763481Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b21fdc9c735cc27","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.90:2379]}","request-path":"/0/members/2b21fdc9c735cc27/attributes","cluster-id":"77163f3b44f8f1db","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T12:25:51.763831Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T12:25:51.763894Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T12:25:51.763991Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T12:25:51.764012Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T12:25:51.771846Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.90:2379"} {"level":"info","ts":"2026-04-23T12:25:51.772409Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T12:25:54.86303Z","caller":"traceutil/trace.go:171","msg":"trace[444123913] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:74; }","duration":"106.768084ms","start":"2026-04-23T12:25:54.756224Z","end":"2026-04-23T12:25:54.862992Z","steps":["trace[444123913] 'read index received' (duration: 16.477801ms)","trace[444123913] 'applied index is now lower than readState.Index' (duration: 90.288913ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:25:54.863214Z","caller":"traceutil/trace.go:171","msg":"trace[234640611] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"109.129945ms","start":"2026-04-23T12:25:54.754071Z","end":"2026-04-23T12:25:54.863201Z","steps":["trace[234640611] 'process raft request' (duration: 58.137715ms)","trace[234640611] 'compare' (duration: 50.607403ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:25:54.863748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.498778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T12:25:54.863821Z","caller":"traceutil/trace.go:171","msg":"trace[1132840450] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"107.606519ms","start":"2026-04-23T12:25:54.756202Z","end":"2026-04-23T12:25:54.863808Z","steps":["trace[1132840450] 'agreement among raft nodes before linearized reading' (duration: 107.481446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:25:55.123655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.37962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-23T12:25:55.123801Z","caller":"traceutil/trace.go:171","msg":"trace[1568534064] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"156.962469ms","start":"2026-04-23T12:25:54.966812Z","end":"2026-04-23T12:25:55.123775Z","steps":["trace[1568534064] 'process raft request' (duration: 33.900778ms)","trace[1568534064] 'compare' (duration: 122.212777ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:25:55.351824Z","caller":"traceutil/trace.go:171","msg":"trace[662069134] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"149.03511ms","start":"2026-04-23T12:25:55.202746Z","end":"2026-04-23T12:25:55.351781Z","steps":["trace[662069134] 'process raft request' (duration: 64.160552ms)","trace[662069134] 'compare' (duration: 84.689114ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:25:55.556055Z","caller":"traceutil/trace.go:171","msg":"trace[1287389528] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"168.85373ms","start":"2026-04-23T12:25:55.387161Z","end":"2026-04-23T12:25:55.556015Z","steps":["trace[1287389528] 'process raft request' (duration: 138.999023ms)","trace[1287389528] 'compare' (duration: 29.609853ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:25:55.922559Z","caller":"traceutil/trace.go:171","msg":"trace[1309447421] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"160.306238ms","start":"2026-04-23T12:25:55.762223Z","end":"2026-04-23T12:25:55.922529Z","steps":["trace[1309447421] 'process raft request' (duration: 134.982362ms)","trace[1309447421] 'compare' (duration: 25.148574ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:25:56.254493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.922133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-23T12:25:56.254611Z","caller":"traceutil/trace.go:171","msg":"trace[1578251033] linearizableReadLoop","detail":"{readStateIndex:89; appliedIndex:87; }","duration":"389.151859ms","start":"2026-04-23T12:25:55.865443Z","end":"2026-04-23T12:25:56.254595Z","steps":["trace[1578251033] 'read index received' (duration: 31.817022ms)","trace[1578251033] 'applied index is now lower than readState.Index' (duration: 357.333627ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:25:56.254792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.354212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T12:25:56.25482Z","caller":"traceutil/trace.go:171","msg":"trace[2118469551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:84; }","duration":"389.443432ms","start":"2026-04-23T12:25:55.865367Z","end":"2026-04-23T12:25:56.25481Z","steps":["trace[2118469551] 'agreement among raft nodes before linearized reading' (duration: 389.332441ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:25:56.254762Z","caller":"traceutil/trace.go:171","msg":"trace[686119832] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"490.630698ms","start":"2026-04-23T12:25:55.764058Z","end":"2026-04-23T12:25:56.254689Z","steps":["trace[686119832] 'process raft request' (duration: 296.43022ms)","trace[686119832] 'compare' (duration: 193.74285ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:25:56.254858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:25:55.865332Z","time spent":"389.519505ms","remote":"127.0.0.1:32826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T12:25:56.255051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:25:55.764048Z","time spent":"490.870372ms","remote":"127.0.0.1:32864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T12:25:56.255212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.037577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T12:25:56.255255Z","caller":"traceutil/trace.go:171","msg":"trace[1137059020] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:84; }","duration":"329.117338ms","start":"2026-04-23T12:25:55.926127Z","end":"2026-04-23T12:25:56.255245Z","steps":["trace[1137059020] 'agreement among raft nodes before linearized reading' (duration: 329.046916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:25:56.255286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:25:55.926108Z","time spent":"329.170659ms","remote":"127.0.0.1:33148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"info","ts":"2026-04-23T12:25:56.471794Z","caller":"traceutil/trace.go:171","msg":"trace[835847176] linearizableReadLoop","detail":"{readStateIndex:91; appliedIndex:90; }","duration":"145.838444ms","start":"2026-04-23T12:25:56.325915Z","end":"2026-04-23T12:25:56.471753Z","steps":["trace[835847176] 'read index received' (duration: 87.299021ms)","trace[835847176] 'applied index is now lower than readState.Index' (duration: 58.537603ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:25:56.471841Z","caller":"traceutil/trace.go:171","msg":"trace[1486653] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"210.503385ms","start":"2026-04-23T12:25:56.261269Z","end":"2026-04-23T12:25:56.471772Z","steps":["trace[1486653] 'process raft request' (duration: 151.995392ms)","trace[1486653] 'compare' (duration: 58.292169ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:25:56.472041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.101399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T12:25:56.4721Z","caller":"traceutil/trace.go:171","msg":"trace[155510773] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:86; }","duration":"146.20798ms","start":"2026-04-23T12:25:56.325876Z","end":"2026-04-23T12:25:56.472084Z","steps":["trace[155510773] 'agreement among raft nodes before linearized reading' (duration: 145.988136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:25:56.716709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.055952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-23T12:25:56.716861Z","caller":"traceutil/trace.go:171","msg":"trace[99807597] linearizableReadLoop","detail":"{readStateIndex:94; appliedIndex:93; }","duration":"168.234539ms","start":"2026-04-23T12:25:56.548604Z","end":"2026-04-23T12:25:56.716838Z","steps":["trace[99807597] 'read index received' (duration: 65.983455ms)","trace[99807597] 'applied index is now lower than readState.Index' (duration: 102.249444ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:25:56.716904Z","caller":"traceutil/trace.go:171","msg":"trace[921551619] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"170.341696ms","start":"2026-04-23T12:25:56.546525Z","end":"2026-04-23T12:25:56.716867Z","steps":["trace[921551619] 'process raft request' (duration: 68.00766ms)","trace[921551619] 'compare' (duration: 101.776906ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:25:56.717008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.391611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a8fc04d341987e\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-04-23T12:25:56.717097Z","caller":"traceutil/trace.go:171","msg":"trace[754571867] range","detail":"{range_begin:/registry/events/default/instance.18a8fc04d341987e; range_end:; response_count:1; response_revision:89; }","duration":"168.502343ms","start":"2026-04-23T12:25:56.548579Z","end":"2026-04-23T12:25:56.717081Z","steps":["trace[754571867] 'agreement among raft nodes before linearized reading' (duration: 168.31958ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:25:57.032419Z","caller":"traceutil/trace.go:171","msg":"trace[1907175859] linearizableReadLoop","detail":"{readStateIndex:96; appliedIndex:95; }","duration":"303.915183ms","start":"2026-04-23T12:25:56.728471Z","end":"2026-04-23T12:25:57.032386Z","steps":["trace[1907175859] 'read index received' (duration: 249.512063ms)","trace[1907175859] 'applied index is now lower than readState.Index' (duration: 54.40185ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:25:57.033021Z","caller":"traceutil/trace.go:171","msg":"trace[1291883999] transaction","detail":"{read_only:false; response_revision:91; number_of_response:1; }","duration":"305.140905ms","start":"2026-04-23T12:25:56.727855Z","end":"2026-04-23T12:25:57.032996Z","steps":["trace[1291883999] 'process raft request' (duration: 250.184865ms)","trace[1291883999] 'compare' (duration: 54.201537ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:25:57.033227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:25:56.727834Z","time spent":"305.300247ms","remote":"127.0.0.1:33148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T12:25:57.033396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.91054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a8fc04d341cbbe\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-23T12:25:57.033525Z","caller":"traceutil/trace.go:171","msg":"trace[1919444065] range","detail":"{range_begin:/registry/events/default/instance.18a8fc04d341cbbe; range_end:; response_count:1; response_revision:91; }","duration":"305.096404ms","start":"2026-04-23T12:25:56.72841Z","end":"2026-04-23T12:25:57.033506Z","steps":["trace[1919444065] 'agreement among raft nodes before linearized reading' (duration: 304.716317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:25:57.033623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.183187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T12:25:57.03367Z","caller":"traceutil/trace.go:171","msg":"trace[1750963071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:91; }","duration":"168.27257ms","start":"2026-04-23T12:25:56.865383Z","end":"2026-04-23T12:25:57.033656Z","steps":["trace[1750963071] 'agreement among raft nodes before linearized reading' (duration: 168.199878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:25:57.033815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:25:56.728388Z","time spent":"305.41049ms","remote":"127.0.0.1:32864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":672,"request content":"key:\"/registry/events/default/instance.18a8fc04d341cbbe\" "} {"level":"info","ts":"2026-04-23T12:25:57.168323Z","caller":"traceutil/trace.go:171","msg":"trace[992546470] transaction","detail":"{read_only:false; response_revision:94; number_of_response:1; }","duration":"121.84503ms","start":"2026-04-23T12:25:57.046412Z","end":"2026-04-23T12:25:57.168257Z","steps":["trace[992546470] 'process raft request' (duration: 90.849413ms)","trace[992546470] 'compare' (duration: 30.799864ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:26:20.22078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900131323130123,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T12:26:20.491983Z","caller":"traceutil/trace.go:171","msg":"trace[2034544201] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"771.536975ms","start":"2026-04-23T12:26:19.720418Z","end":"2026-04-23T12:26:20.491955Z","steps":["trace[2034544201] 'read index received' (duration: 698.503912ms)","trace[2034544201] 'applied index is now lower than readState.Index' (duration: 73.031853ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:26:20.49208Z","caller":"traceutil/trace.go:171","msg":"trace[1683747562] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"1.11189724s","start":"2026-04-23T12:26:19.380139Z","end":"2026-04-23T12:26:20.492036Z","steps":["trace[1683747562] 'process raft request' (duration: 1.038755954s)","trace[1683747562] 'compare' (duration: 72.525338ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:26:20.492245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.814613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T12:26:20.492314Z","caller":"traceutil/trace.go:171","msg":"trace[1672012293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:469; }","duration":"771.970008ms","start":"2026-04-23T12:26:19.720328Z","end":"2026-04-23T12:26:20.492298Z","steps":["trace[1672012293] 'agreement among raft nodes before linearized reading' (duration: 771.749061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:20.49237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:19.720285Z","time spent":"772.076431ms","remote":"127.0.0.1:33046","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-23T12:26:20.492542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:19.380131Z","time spent":"1.112053584s","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:26:20.541405Z","caller":"traceutil/trace.go:171","msg":"trace[2104541369] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"117.900216ms","start":"2026-04-23T12:26:20.423469Z","end":"2026-04-23T12:26:20.541369Z","steps":["trace[2104541369] 'process raft request' (duration: 117.695659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:20.541613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.257959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-23T12:26:20.541705Z","caller":"traceutil/trace.go:171","msg":"trace[233960326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:470; }","duration":"268.423734ms","start":"2026-04-23T12:26:20.273257Z","end":"2026-04-23T12:26:20.54168Z","steps":["trace[233960326] 'agreement among raft nodes before linearized reading' (duration: 268.119855ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:26.447709Z","caller":"traceutil/trace.go:171","msg":"trace[14817397] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"598.118373ms","start":"2026-04-23T12:26:25.849554Z","end":"2026-04-23T12:26:26.447673Z","steps":["trace[14817397] 'process raft request' (duration: 597.911277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:26.44797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:25.849537Z","time spent":"598.303749ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T12:26:26.456325Z","caller":"traceutil/trace.go:171","msg":"trace[1078024143] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"606.231582ms","start":"2026-04-23T12:26:25.850058Z","end":"2026-04-23T12:26:26.45629Z","steps":["trace[1078024143] 'process raft request' (duration: 606.025216ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:26.456485Z","caller":"traceutil/trace.go:171","msg":"trace[1805621585] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"596.122354ms","start":"2026-04-23T12:26:25.860353Z","end":"2026-04-23T12:26:26.456475Z","steps":["trace[1805621585] 'process raft request' (duration: 595.923979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:26.456552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:25.860341Z","time spent":"596.166546ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T12:26:26.456554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:25.850014Z","time spent":"606.452289ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T12:26:26.456657Z","caller":"traceutil/trace.go:171","msg":"trace[602214170] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"596.537817ms","start":"2026-04-23T12:26:25.860106Z","end":"2026-04-23T12:26:26.456644Z","steps":["trace[602214170] 'process raft request' (duration: 596.118674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:26.456713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:25.860072Z","time spent":"596.615248ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T12:26:26.456732Z","caller":"traceutil/trace.go:171","msg":"trace[699485457] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"594.227809ms","start":"2026-04-23T12:26:25.862498Z","end":"2026-04-23T12:26:26.456726Z","steps":["trace[699485457] 'process raft request' (duration: 593.855018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:26.456757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:25.862491Z","time spent":"594.25378ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T12:26:26.456822Z","caller":"traceutil/trace.go:171","msg":"trace[888766709] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"590.85892ms","start":"2026-04-23T12:26:25.865952Z","end":"2026-04-23T12:26:26.456811Z","steps":["trace[888766709] 'process raft request' (duration: 590.446238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:26.456874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:25.865941Z","time spent":"590.909011ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T12:26:26.457081Z","caller":"traceutil/trace.go:171","msg":"trace[809530782] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"586.270414ms","start":"2026-04-23T12:26:25.870799Z","end":"2026-04-23T12:26:26.45707Z","steps":["trace[809530782] 'process raft request' (duration: 585.648746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:26.457123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:25.870785Z","time spent":"586.318665ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T12:26:26.457144Z","caller":"traceutil/trace.go:171","msg":"trace[61335313] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"583.880194ms","start":"2026-04-23T12:26:25.873252Z","end":"2026-04-23T12:26:26.457133Z","steps":["trace[61335313] 'process raft request' (duration: 583.42171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:26.457203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:25.873235Z","time spent":"583.942555ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T12:26:26.457406Z","caller":"traceutil/trace.go:171","msg":"trace[1348300844] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"584.047989ms","start":"2026-04-23T12:26:25.873344Z","end":"2026-04-23T12:26:26.457392Z","steps":["trace[1348300844] 'process raft request' (duration: 583.642186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:26.457465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:25.873337Z","time spent":"584.10284ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T12:26:26.457655Z","caller":"traceutil/trace.go:171","msg":"trace[1988476615] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"576.409293ms","start":"2026-04-23T12:26:25.881209Z","end":"2026-04-23T12:26:26.457618Z","steps":["trace[1988476615] 'process raft request' (duration: 576.069183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:26.457795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:25.88116Z","time spent":"576.568788ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T12:26:26.458026Z","caller":"traceutil/trace.go:171","msg":"trace[1664659386] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"395.109188ms","start":"2026-04-23T12:26:26.062902Z","end":"2026-04-23T12:26:26.458011Z","steps":["trace[1664659386] 'process raft request' (duration: 394.86302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:26.458143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:26.062852Z","time spent":"395.229591ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T12:26:26.458044Z","caller":"traceutil/trace.go:171","msg":"trace[921938219] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"546.295296ms","start":"2026-04-23T12:26:25.911735Z","end":"2026-04-23T12:26:26.45803Z","steps":["trace[921938219] 'process raft request' (duration: 545.680007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:26.458234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:25.911697Z","time spent":"546.509632ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T12:26:26.628308Z","caller":"traceutil/trace.go:171","msg":"trace[1262745467] transaction","detail":"{read_only:false; number_of_response:1; response_revision:531; }","duration":"173.429504ms","start":"2026-04-23T12:26:26.454845Z","end":"2026-04-23T12:26:26.628274Z","steps":["trace[1262745467] 'process raft request' (duration: 173.369842ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:26.628366Z","caller":"traceutil/trace.go:171","msg":"trace[1472980649] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"173.487425ms","start":"2026-04-23T12:26:26.454845Z","end":"2026-04-23T12:26:26.628332Z","steps":["trace[1472980649] 'process raft request' (duration: 173.294929ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:26.629077Z","caller":"traceutil/trace.go:171","msg":"trace[1967525335] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"165.042085ms","start":"2026-04-23T12:26:26.464022Z","end":"2026-04-23T12:26:26.629064Z","steps":["trace[1967525335] 'process raft request' (duration: 164.724325ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:26.629271Z","caller":"traceutil/trace.go:171","msg":"trace[996773104] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"165.047585ms","start":"2026-04-23T12:26:26.464167Z","end":"2026-04-23T12:26:26.629215Z","steps":["trace[996773104] 'process raft request' (duration: 164.814138ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:26.629344Z","caller":"traceutil/trace.go:171","msg":"trace[930011103] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"175.228586ms","start":"2026-04-23T12:26:26.45405Z","end":"2026-04-23T12:26:26.629279Z","steps":["trace[930011103] 'process raft request' (duration: 80.837444ms)","trace[930011103] 'compare' (duration: 93.030472ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:26:26.631483Z","caller":"traceutil/trace.go:171","msg":"trace[114447159] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"165.702885ms","start":"2026-04-23T12:26:26.465739Z","end":"2026-04-23T12:26:26.631442Z","steps":["trace[114447159] 'process raft request' (duration: 165.472708ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:26.631764Z","caller":"traceutil/trace.go:171","msg":"trace[1836106151] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"158.989157ms","start":"2026-04-23T12:26:26.472761Z","end":"2026-04-23T12:26:26.63175Z","steps":["trace[1836106151] 'process raft request' (duration: 158.571935ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:26.631923Z","caller":"traceutil/trace.go:171","msg":"trace[306931941] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"154.212487ms","start":"2026-04-23T12:26:26.477687Z","end":"2026-04-23T12:26:26.631899Z","steps":["trace[306931941] 'process raft request' (duration: 153.913119ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:26.632006Z","caller":"traceutil/trace.go:171","msg":"trace[823742884] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"159.243654ms","start":"2026-04-23T12:26:26.472752Z","end":"2026-04-23T12:26:26.631995Z","steps":["trace[823742884] 'process raft request' (duration: 158.633927ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:26.632076Z","caller":"traceutil/trace.go:171","msg":"trace[2000736374] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"157.649998ms","start":"2026-04-23T12:26:26.474397Z","end":"2026-04-23T12:26:26.632047Z","steps":["trace[2000736374] 'process raft request' (duration: 157.0542ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:26.63212Z","caller":"traceutil/trace.go:171","msg":"trace[687769219] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"117.765511ms","start":"2026-04-23T12:26:26.514338Z","end":"2026-04-23T12:26:26.632103Z","steps":["trace[687769219] 'process raft request' (duration: 117.642148ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:26.632114Z","caller":"traceutil/trace.go:171","msg":"trace[1213424549] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"152.31057ms","start":"2026-04-23T12:26:26.479788Z","end":"2026-04-23T12:26:26.632099Z","steps":["trace[1213424549] 'process raft request' (duration: 152.034503ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:29.724276Z","caller":"traceutil/trace.go:171","msg":"trace[743624065] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"194.609298ms","start":"2026-04-23T12:26:29.529619Z","end":"2026-04-23T12:26:29.724228Z","steps":["trace[743624065] 'process raft request' (duration: 194.271448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:29.724643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.158137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T12:26:29.724136Z","caller":"traceutil/trace.go:171","msg":"trace[1579602606] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"168.5745ms","start":"2026-04-23T12:26:29.55553Z","end":"2026-04-23T12:26:29.724105Z","steps":["trace[1579602606] 'read index received' (duration: 168.349433ms)","trace[1579602606] 'applied index is now lower than readState.Index' (duration: 223.437µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:26:29.724897Z","caller":"traceutil/trace.go:171","msg":"trace[267338889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"169.489148ms","start":"2026-04-23T12:26:29.555383Z","end":"2026-04-23T12:26:29.724872Z","steps":["trace[267338889] 'agreement among raft nodes before linearized reading' (duration: 168.919831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:29.725099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.563633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:26:29.725167Z","caller":"traceutil/trace.go:171","msg":"trace[463403431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"163.643085ms","start":"2026-04-23T12:26:29.561504Z","end":"2026-04-23T12:26:29.725148Z","steps":["trace[463403431] 'agreement among raft nodes before linearized reading' (duration: 163.48235ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:30.05053Z","caller":"traceutil/trace.go:171","msg":"trace[1112586457] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"316.275064ms","start":"2026-04-23T12:26:29.734218Z","end":"2026-04-23T12:26:30.050494Z","steps":["trace[1112586457] 'process raft request' (duration: 316.098819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:30.051767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:29.734184Z","time spent":"316.975425ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:26:30.079663Z","caller":"traceutil/trace.go:171","msg":"trace[1209591547] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"344.667011ms","start":"2026-04-23T12:26:29.734964Z","end":"2026-04-23T12:26:30.079631Z","steps":["trace[1209591547] 'process raft request' (duration: 344.60123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:30.079865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:29.73495Z","time spent":"344.802204ms","remote":"127.0.0.1:32890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T12:26:30.080284Z","caller":"traceutil/trace.go:171","msg":"trace[162064456] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"345.796425ms","start":"2026-04-23T12:26:29.73447Z","end":"2026-04-23T12:26:30.080266Z","steps":["trace[162064456] 'process raft request' (duration: 344.930009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:30.080333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:29.734436Z","time spent":"345.873548ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T12:26:30.408954Z","caller":"traceutil/trace.go:171","msg":"trace[1848262371] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"265.58635ms","start":"2026-04-23T12:26:30.143315Z","end":"2026-04-23T12:26:30.408901Z","steps":["trace[1848262371] 'process raft request' (duration: 263.615901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:30.754718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.543621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:26:30.754855Z","caller":"traceutil/trace.go:171","msg":"trace[1229350549] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:605; }","duration":"280.723866ms","start":"2026-04-23T12:26:30.474092Z","end":"2026-04-23T12:26:30.754816Z","steps":["trace[1229350549] 'range keys from in-memory index tree' (duration: 280.459008ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:30.755196Z","caller":"traceutil/trace.go:171","msg":"trace[231915857] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"138.214185ms","start":"2026-04-23T12:26:30.616948Z","end":"2026-04-23T12:26:30.755162Z","steps":["trace[231915857] 'process raft request' (duration: 91.484457ms)","trace[231915857] 'compare' (duration: 46.410348ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:26:31.367866Z","caller":"traceutil/trace.go:171","msg":"trace[1037744962] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"607.339725ms","start":"2026-04-23T12:26:30.760493Z","end":"2026-04-23T12:26:31.367833Z","steps":["trace[1037744962] 'process raft request' (duration: 515.285721ms)","trace[1037744962] 'compare' (duration: 91.750075ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:26:31.368336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:30.760463Z","time spent":"607.746847ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:26:31.368548Z","caller":"traceutil/trace.go:171","msg":"trace[22568256] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"606.81497ms","start":"2026-04-23T12:26:30.761724Z","end":"2026-04-23T12:26:31.368539Z","steps":["trace[22568256] 'process raft request' (duration: 606.321876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:31.3687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:30.761692Z","time spent":"606.895813ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:26:31.368352Z","caller":"traceutil/trace.go:171","msg":"trace[916764280] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"606.863771ms","start":"2026-04-23T12:26:30.761444Z","end":"2026-04-23T12:26:31.368308Z","steps":["trace[916764280] 'process raft request' (duration: 606.336245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:31.368814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:30.76141Z","time spent":"607.333285ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:26:31.3692Z","caller":"traceutil/trace.go:171","msg":"trace[1213155615] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"215.05653ms","start":"2026-04-23T12:26:31.154108Z","end":"2026-04-23T12:26:31.369165Z","steps":["trace[1213155615] 'process raft request' (duration: 214.419591ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:31.620837Z","caller":"traceutil/trace.go:171","msg":"trace[2056388030] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"166.973213ms","start":"2026-04-23T12:26:31.45383Z","end":"2026-04-23T12:26:31.620803Z","steps":["trace[2056388030] 'process raft request' (duration: 100.416131ms)","trace[2056388030] 'compare' (duration: 65.914603ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:26:32.236693Z","caller":"traceutil/trace.go:171","msg":"trace[1559786266] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"164.682675ms","start":"2026-04-23T12:26:32.071959Z","end":"2026-04-23T12:26:32.236641Z","steps":["trace[1559786266] 'process raft request' (duration: 132.30082ms)","trace[1559786266] 'compare' (duration: 31.759916ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:26:32.887354Z","caller":"traceutil/trace.go:171","msg":"trace[725283013] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"143.641655ms","start":"2026-04-23T12:26:32.743684Z","end":"2026-04-23T12:26:32.887326Z","steps":["trace[725283013] 'process raft request' (duration: 141.550664ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:34.479087Z","caller":"traceutil/trace.go:171","msg":"trace[1057562404] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"408.974877ms","start":"2026-04-23T12:26:34.070077Z","end":"2026-04-23T12:26:34.479052Z","steps":["trace[1057562404] 'process raft request' (duration: 408.786601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:34.47926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:34.07005Z","time spent":"409.128762ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:26:34.710199Z","caller":"traceutil/trace.go:171","msg":"trace[1302211807] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"101.153642ms","start":"2026-04-23T12:26:34.609022Z","end":"2026-04-23T12:26:34.710176Z","steps":["trace[1302211807] 'read index received' (duration: 100.991638ms)","trace[1302211807] 'applied index is now lower than readState.Index' (duration: 161.244µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:26:34.710421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.359828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T12:26:34.710443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.281156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T12:26:34.710543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.374089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:26:34.710983Z","caller":"traceutil/trace.go:171","msg":"trace[882774635] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"133.237558ms","start":"2026-04-23T12:26:34.577017Z","end":"2026-04-23T12:26:34.710255Z","steps":["trace[882774635] 'process raft request' (duration: 133.041712ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:34.711066Z","caller":"traceutil/trace.go:171","msg":"trace[320242329] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:626; }","duration":"101.500344ms","start":"2026-04-23T12:26:34.608988Z","end":"2026-04-23T12:26:34.710489Z","steps":["trace[320242329] 'agreement among raft nodes before linearized reading' (duration: 101.272337ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:34.711174Z","caller":"traceutil/trace.go:171","msg":"trace[1452295004] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:626; }","duration":"101.406269ms","start":"2026-04-23T12:26:34.609111Z","end":"2026-04-23T12:26:34.710517Z","steps":["trace[1452295004] 'agreement among raft nodes before linearized reading' (duration: 101.204943ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:34.711228Z","caller":"traceutil/trace.go:171","msg":"trace[1188562546] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:626; }","duration":"100.412781ms","start":"2026-04-23T12:26:34.610148Z","end":"2026-04-23T12:26:34.710561Z","steps":["trace[1188562546] 'agreement among raft nodes before linearized reading' (duration: 100.373799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:37.256044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.982358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-23T12:26:37.256143Z","caller":"traceutil/trace.go:171","msg":"trace[1189056846] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:640; }","duration":"196.153173ms","start":"2026-04-23T12:26:37.059968Z","end":"2026-04-23T12:26:37.256121Z","steps":["trace[1189056846] 'range keys from in-memory index tree' (duration: 195.860325ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:39.408486Z","caller":"traceutil/trace.go:171","msg":"trace[713520680] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"254.247826ms","start":"2026-04-23T12:26:39.154207Z","end":"2026-04-23T12:26:39.408455Z","steps":["trace[713520680] 'process raft request' (duration: 238.351307ms)","trace[713520680] 'compare' (duration: 15.663982ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:26:39.41252Z","caller":"traceutil/trace.go:171","msg":"trace[1645042281] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"258.087948ms","start":"2026-04-23T12:26:39.154405Z","end":"2026-04-23T12:26:39.412493Z","steps":["trace[1645042281] 'process raft request' (duration: 257.686417ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:42.944339Z","caller":"traceutil/trace.go:171","msg":"trace[1996189426] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"160.604005ms","start":"2026-04-23T12:26:42.783704Z","end":"2026-04-23T12:26:42.944308Z","steps":["trace[1996189426] 'process raft request' (duration: 127.360404ms)","trace[1996189426] 'compare' (duration: 32.823938ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:26:45.587394Z","caller":"traceutil/trace.go:171","msg":"trace[1729020462] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"122.638226ms","start":"2026-04-23T12:26:45.464691Z","end":"2026-04-23T12:26:45.587329Z","steps":["trace[1729020462] 'process raft request' (duration: 122.284255ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:45.869925Z","caller":"traceutil/trace.go:171","msg":"trace[1424060939] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"124.733657ms","start":"2026-04-23T12:26:45.745161Z","end":"2026-04-23T12:26:45.869894Z","steps":["trace[1424060939] 'process raft request' (duration: 124.586113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:52.116887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900131323130771,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T12:26:52.34999Z","caller":"traceutil/trace.go:171","msg":"trace[419909968] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"734.00861ms","start":"2026-04-23T12:26:51.615945Z","end":"2026-04-23T12:26:52.349954Z","steps":["trace[419909968] 'read index received' (duration: 733.98396ms)","trace[419909968] 'applied index is now lower than readState.Index' (duration: 22.49µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:26:52.350224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.258357ms","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-23T12:26:52.350305Z","caller":"traceutil/trace.go:171","msg":"trace[304795660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:783; }","duration":"734.38287ms","start":"2026-04-23T12:26:51.615901Z","end":"2026-04-23T12:26:52.350284Z","steps":["trace[304795660] 'agreement among raft nodes before linearized reading' (duration: 734.159643ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:52.350302Z","caller":"traceutil/trace.go:171","msg":"trace[1614095084] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"737.488372ms","start":"2026-04-23T12:26:51.612792Z","end":"2026-04-23T12:26:52.350281Z","steps":["trace[1614095084] 'process raft request' (duration: 736.990057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:52.350354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:51.615888Z","time spent":"734.451812ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T12:26:52.350419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:51.61277Z","time spent":"737.574394ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:26:52.81093Z","caller":"traceutil/trace.go:171","msg":"trace[81734042] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"250.137215ms","start":"2026-04-23T12:26:52.56076Z","end":"2026-04-23T12:26:52.810897Z","steps":["trace[81734042] 'read index received' (duration: 248.93091ms)","trace[81734042] 'applied index is now lower than readState.Index' (duration: 1.205075ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:26:52.811279Z","caller":"traceutil/trace.go:171","msg":"trace[1944808783] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"456.112657ms","start":"2026-04-23T12:26:52.35509Z","end":"2026-04-23T12:26:52.811203Z","steps":["trace[1944808783] 'process raft request' (duration: 454.658014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:26:52.8113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.529046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T12:26:52.811407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.15363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-23T12:26:52.811406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.930216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T12:26:52.811435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:26:52.355067Z","time spent":"456.291312ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:26:52.811449Z","caller":"traceutil/trace.go:171","msg":"trace[1956080386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"221.240932ms","start":"2026-04-23T12:26:52.590198Z","end":"2026-04-23T12:26:52.811439Z","steps":["trace[1956080386] 'agreement among raft nodes before linearized reading' (duration: 221.104368ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:52.811455Z","caller":"traceutil/trace.go:171","msg":"trace[998539690] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:784; }","duration":"176.084251ms","start":"2026-04-23T12:26:52.635361Z","end":"2026-04-23T12:26:52.811445Z","steps":["trace[998539690] 'agreement among raft nodes before linearized reading' (duration: 175.976008ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:26:52.811482Z","caller":"traceutil/trace.go:171","msg":"trace[1066145105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"250.726602ms","start":"2026-04-23T12:26:52.560693Z","end":"2026-04-23T12:26:52.811419Z","steps":["trace[1066145105] 'agreement among raft nodes before linearized reading' (duration: 250.544156ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:12.773747Z","caller":"traceutil/trace.go:171","msg":"trace[530321555] linearizableReadLoop","detail":"{readStateIndex:1623; appliedIndex:1622; }","duration":"213.532898ms","start":"2026-04-23T12:30:12.560189Z","end":"2026-04-23T12:30:12.773722Z","steps":["trace[530321555] 'read index received' (duration: 213.378164ms)","trace[530321555] 'applied index is now lower than readState.Index' (duration: 153.604µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:30:12.773772Z","caller":"traceutil/trace.go:171","msg":"trace[296722249] transaction","detail":"{read_only:false; response_revision:1554; number_of_response:1; }","duration":"292.080887ms","start":"2026-04-23T12:30:12.481665Z","end":"2026-04-23T12:30:12.773746Z","steps":["trace[296722249] 'process raft request' (duration: 291.915591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:12.773866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.666732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:30:12.773898Z","caller":"traceutil/trace.go:171","msg":"trace[1827724786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1554; }","duration":"213.747355ms","start":"2026-04-23T12:30:12.560139Z","end":"2026-04-23T12:30:12.773886Z","steps":["trace[1827724786] 'agreement among raft nodes before linearized reading' (duration: 213.683763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:13.754159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.592588ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-23T12:30:13.754263Z","caller":"traceutil/trace.go:171","msg":"trace[1230570761] linearizableReadLoop","detail":"{readStateIndex:1624; appliedIndex:1623; }","duration":"424.631529ms","start":"2026-04-23T12:30:13.329615Z","end":"2026-04-23T12:30:13.754247Z","steps":["trace[1230570761] 'read index received' (duration: 288.001559ms)","trace[1230570761] 'applied index is now lower than readState.Index' (duration: 136.62876ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:30:13.754452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.805234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T12:30:13.754487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.798148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T12:30:13.754529Z","caller":"traceutil/trace.go:171","msg":"trace[1247636846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1554; }","duration":"369.88021ms","start":"2026-04-23T12:30:13.38464Z","end":"2026-04-23T12:30:13.754521Z","steps":["trace[1247636846] 'agreement among raft nodes before linearized reading' (duration: 369.737356ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:13.754525Z","caller":"traceutil/trace.go:171","msg":"trace[969971340] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1554; }","duration":"424.932676ms","start":"2026-04-23T12:30:13.329575Z","end":"2026-04-23T12:30:13.754508Z","steps":["trace[969971340] 'agreement among raft nodes before linearized reading' (duration: 424.771002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:13.754559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:13.384618Z","time spent":"369.932822ms","remote":"127.0.0.1:33046","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-23T12:30:13.754572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:13.329557Z","time spent":"425.000559ms","remote":"127.0.0.1:32890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-04-23T12:30:13.75476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.500851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:30:13.754815Z","caller":"traceutil/trace.go:171","msg":"trace[147874863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1554; }","duration":"192.596503ms","start":"2026-04-23T12:30:13.56221Z","end":"2026-04-23T12:30:13.754806Z","steps":["trace[147874863] 'agreement among raft nodes before linearized reading' (duration: 192.51057ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:38.207288Z","caller":"traceutil/trace.go:171","msg":"trace[1789650109] linearizableReadLoop","detail":"{readStateIndex:1712; appliedIndex:1711; }","duration":"123.496515ms","start":"2026-04-23T12:30:38.083766Z","end":"2026-04-23T12:30:38.207262Z","steps":["trace[1789650109] 'read index received' (duration: 123.430154ms)","trace[1789650109] 'applied index is now lower than readState.Index' (duration: 65.481µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:30:38.207517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.734663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-23T12:30:38.207567Z","caller":"traceutil/trace.go:171","msg":"trace[601680457] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1637; }","duration":"123.822455ms","start":"2026-04-23T12:30:38.083732Z","end":"2026-04-23T12:30:38.207554Z","steps":["trace[601680457] 'agreement among raft nodes before linearized reading' (duration: 123.696001ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:38.207393Z","caller":"traceutil/trace.go:171","msg":"trace[1129908374] transaction","detail":"{read_only:false; response_revision:1637; number_of_response:1; }","duration":"156.896323ms","start":"2026-04-23T12:30:38.050461Z","end":"2026-04-23T12:30:38.207357Z","steps":["trace[1129908374] 'process raft request' (duration: 156.652687ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:38.222544Z","caller":"traceutil/trace.go:171","msg":"trace[889280087] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"107.978011ms","start":"2026-04-23T12:30:38.114534Z","end":"2026-04-23T12:30:38.222512Z","steps":["trace[889280087] 'process raft request' (duration: 107.795275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:39.136372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.30492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T12:30:39.13649Z","caller":"traceutil/trace.go:171","msg":"trace[153736192] linearizableReadLoop","detail":"{readStateIndex:1714; appliedIndex:1713; }","duration":"578.02174ms","start":"2026-04-23T12:30:38.558452Z","end":"2026-04-23T12:30:39.136474Z","steps":["trace[153736192] 'read index received' (duration: 294.451053ms)","trace[153736192] 'applied index is now lower than readState.Index' (duration: 283.569527ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:30:39.136602Z","caller":"traceutil/trace.go:171","msg":"trace[745335045] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"841.555025ms","start":"2026-04-23T12:30:38.295035Z","end":"2026-04-23T12:30:39.13659Z","steps":["trace[745335045] 'process raft request' (duration: 557.962088ms)","trace[745335045] 'compare' (duration: 283.186746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:30:39.136666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:38.295009Z","time spent":"841.624997ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T12:30:39.136952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.499243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:30:39.137004Z","caller":"traceutil/trace.go:171","msg":"trace[774730380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1639; }","duration":"578.588375ms","start":"2026-04-23T12:30:38.558404Z","end":"2026-04-23T12:30:39.136993Z","steps":["trace[774730380] 'agreement among raft nodes before linearized reading' (duration: 578.502303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:39.137026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:38.558205Z","time spent":"578.817552ms","remote":"127.0.0.1:32826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T12:30:39.137006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.039803ms","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-23T12:30:39.137084Z","caller":"traceutil/trace.go:171","msg":"trace[977195222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1639; }","duration":"247.147655ms","start":"2026-04-23T12:30:38.889921Z","end":"2026-04-23T12:30:39.137068Z","steps":["trace[977195222] 'agreement among raft nodes before linearized reading' (duration: 246.963789ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:39.539388Z","caller":"traceutil/trace.go:171","msg":"trace[1976455005] linearizableReadLoop","detail":"{readStateIndex:1715; appliedIndex:1714; }","duration":"156.585414ms","start":"2026-04-23T12:30:39.382785Z","end":"2026-04-23T12:30:39.539371Z","steps":["trace[1976455005] 'read index received' (duration: 124.837333ms)","trace[1976455005] 'applied index is now lower than readState.Index' (duration: 31.747241ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:30:39.53945Z","caller":"traceutil/trace.go:171","msg":"trace[165102344] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"398.311077ms","start":"2026-04-23T12:30:39.141117Z","end":"2026-04-23T12:30:39.539428Z","steps":["trace[165102344] 'process raft request' (duration: 366.594588ms)","trace[165102344] 'compare' (duration: 31.573095ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:30:39.539573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.776569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T12:30:39.539922Z","caller":"traceutil/trace.go:171","msg":"trace[523963504] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1640; }","duration":"157.227592ms","start":"2026-04-23T12:30:39.382663Z","end":"2026-04-23T12:30:39.539891Z","steps":["trace[523963504] 'agreement among raft nodes before linearized reading' (duration: 156.827461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:39.539987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:39.141095Z","time spent":"398.74408ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:30:40.027247Z","caller":"traceutil/trace.go:171","msg":"trace[14806940] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"123.677351ms","start":"2026-04-23T12:30:39.903544Z","end":"2026-04-23T12:30:40.027221Z","steps":["trace[14806940] 'process raft request' (duration: 123.514837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:41.063085Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900131323132946,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T12:30:41.43502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255616563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:30:41.435118Z","caller":"traceutil/trace.go:171","msg":"trace[1295247870] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1643; }","duration":"1.255759976s","start":"2026-04-23T12:30:40.17934Z","end":"2026-04-23T12:30:41.4351Z","steps":["trace[1295247870] 'count revisions from in-memory index tree' (duration: 1.25552909s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:41.435159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:40.179321Z","time spent":"1.255827849s","remote":"127.0.0.1:60986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T12:30:41.435657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223952654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T12:30:41.435746Z","caller":"traceutil/trace.go:171","msg":"trace[318054328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1643; }","duration":"1.22413083s","start":"2026-04-23T12:30:40.211595Z","end":"2026-04-23T12:30:41.435726Z","steps":["trace[318054328] 'range keys from in-memory index tree' (duration: 1.223728118s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:41.435801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:40.211578Z","time spent":"1.224203642s","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T12:30:41.475621Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.247835174s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T12:30:41.47644Z","caller":"traceutil/trace.go:171","msg":"trace[854989642] linearizableReadLoop","detail":"{readStateIndex:1719; appliedIndex:1718; }","duration":"914.117761ms","start":"2026-04-23T12:30:40.562287Z","end":"2026-04-23T12:30:41.476405Z","steps":["trace[854989642] 'read index received' (duration: 913.798172ms)","trace[854989642] 'applied index is now lower than readState.Index' (duration: 317.789µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:30:41.476655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.339217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:30:41.476702Z","caller":"traceutil/trace.go:171","msg":"trace[1749427174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1644; }","duration":"914.442279ms","start":"2026-04-23T12:30:40.562248Z","end":"2026-04-23T12:30:41.476691Z","steps":["trace[1749427174] 'agreement among raft nodes before linearized reading' (duration: 914.336467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:41.476749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:40.562233Z","time spent":"914.505622ms","remote":"127.0.0.1:32826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T12:30:41.477593Z","caller":"traceutil/trace.go:171","msg":"trace[1292384025] transaction","detail":"{read_only:false; response_revision:1644; number_of_response:1; }","duration":"1.248954326s","start":"2026-04-23T12:30:40.227655Z","end":"2026-04-23T12:30:41.47661Z","steps":["trace[1292384025] 'process raft request' (duration: 1.248501814s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:41.477751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:40.227619Z","time spent":"1.250032397s","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:30:41.693521Z","caller":"traceutil/trace.go:171","msg":"trace[159396797] linearizableReadLoop","detail":"{readStateIndex:1720; appliedIndex:1719; }","duration":"216.943128ms","start":"2026-04-23T12:30:41.476547Z","end":"2026-04-23T12:30:41.69349Z","steps":["trace[159396797] 'read index received' (duration: 132.711934ms)","trace[159396797] 'applied index is now lower than readState.Index' (duration: 84.229984ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:30:41.693645Z","caller":"traceutil/trace.go:171","msg":"trace[1660156329] transaction","detail":"{read_only:false; response_revision:1645; number_of_response:1; }","duration":"253.289737ms","start":"2026-04-23T12:30:41.440307Z","end":"2026-04-23T12:30:41.693597Z","steps":["trace[1660156329] 'process raft request' (duration: 169.025062ms)","trace[1660156329] 'compare' (duration: 83.888284ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:30:41.693725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.332522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T12:30:41.693733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.36995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T12:30:41.693782Z","caller":"traceutil/trace.go:171","msg":"trace[768230967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1645; }","duration":"215.530007ms","start":"2026-04-23T12:30:41.478236Z","end":"2026-04-23T12:30:41.693765Z","steps":["trace[768230967] 'agreement among raft nodes before linearized reading' (duration: 215.429944ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:41.6938Z","caller":"traceutil/trace.go:171","msg":"trace[1586340123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1645; }","duration":"552.487693ms","start":"2026-04-23T12:30:41.141296Z","end":"2026-04-23T12:30:41.693784Z","steps":["trace[1586340123] 'agreement among raft nodes before linearized reading' (duration: 552.295429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:41.693835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:41.141269Z","time spent":"552.558275ms","remote":"127.0.0.1:33046","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-23T12:30:41.693949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.483166ms","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-23T12:30:41.694016Z","caller":"traceutil/trace.go:171","msg":"trace[138014379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1645; }","duration":"148.626901ms","start":"2026-04-23T12:30:41.545372Z","end":"2026-04-23T12:30:41.693999Z","steps":["trace[138014379] 'agreement among raft nodes before linearized reading' (duration: 148.420875ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:42.381931Z","caller":"traceutil/trace.go:171","msg":"trace[397931026] linearizableReadLoop","detail":"{readStateIndex:1723; appliedIndex:1722; }","duration":"300.478991ms","start":"2026-04-23T12:30:42.081425Z","end":"2026-04-23T12:30:42.381904Z","steps":["trace[397931026] 'read index received' (duration: 300.342548ms)","trace[397931026] 'applied index is now lower than readState.Index' (duration: 135.333µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:30:42.381946Z","caller":"traceutil/trace.go:171","msg":"trace[1899086858] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"344.546048ms","start":"2026-04-23T12:30:42.03737Z","end":"2026-04-23T12:30:42.381916Z","steps":["trace[1899086858] 'process raft request' (duration: 344.383513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:42.382396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.969285ms","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-23T12:30:42.382523Z","caller":"traceutil/trace.go:171","msg":"trace[1186758154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1648; }","duration":"301.178082ms","start":"2026-04-23T12:30:42.081338Z","end":"2026-04-23T12:30:42.382517Z","steps":["trace[1186758154] 'agreement among raft nodes before linearized reading' (duration: 300.649567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:42.382567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:42.081308Z","time spent":"301.251844ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T12:30:42.382637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:42.037344Z","time spent":"344.677003ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:30:42.666424Z","caller":"traceutil/trace.go:171","msg":"trace[1195347204] linearizableReadLoop","detail":"{readStateIndex:1724; appliedIndex:1723; }","duration":"103.35059ms","start":"2026-04-23T12:30:42.563052Z","end":"2026-04-23T12:30:42.666402Z","steps":["trace[1195347204] 'read index received' (duration: 7.679915ms)","trace[1195347204] 'applied index is now lower than readState.Index' (duration: 95.669675ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:30:42.666523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.464823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:30:42.666548Z","caller":"traceutil/trace.go:171","msg":"trace[191597452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1649; }","duration":"103.536296ms","start":"2026-04-23T12:30:42.563004Z","end":"2026-04-23T12:30:42.66654Z","steps":["trace[191597452] 'agreement among raft nodes before linearized reading' (duration: 103.474164ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:42.666538Z","caller":"traceutil/trace.go:171","msg":"trace[1883998924] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"280.069859ms","start":"2026-04-23T12:30:42.386442Z","end":"2026-04-23T12:30:42.666512Z","steps":["trace[1883998924] 'process raft request' (duration: 184.351833ms)","trace[1883998924] 'compare' (duration: 95.357075ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:30:44.5665Z","caller":"traceutil/trace.go:171","msg":"trace[1447084803] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"174.178548ms","start":"2026-04-23T12:30:44.392278Z","end":"2026-04-23T12:30:44.566456Z","steps":["trace[1447084803] 'process raft request' (duration: 173.905651ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:44.860045Z","caller":"traceutil/trace.go:171","msg":"trace[1851952607] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"110.895491ms","start":"2026-04-23T12:30:44.749114Z","end":"2026-04-23T12:30:44.86001Z","steps":["trace[1851952607] 'process raft request' (duration: 110.693926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:45.661212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.120446ms","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-23T12:30:45.661406Z","caller":"traceutil/trace.go:171","msg":"trace[844139259] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1655; }","duration":"401.330292ms","start":"2026-04-23T12:30:45.260048Z","end":"2026-04-23T12:30:45.661379Z","steps":["trace[844139259] 'range keys from in-memory index tree' (duration: 401.095144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:45.661733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.815801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T12:30:45.66191Z","caller":"traceutil/trace.go:171","msg":"trace[233576665] linearizableReadLoop","detail":"{readStateIndex:1732; appliedIndex:1731; }","duration":"277.24256ms","start":"2026-04-23T12:30:45.384596Z","end":"2026-04-23T12:30:45.661838Z","steps":["trace[233576665] 'read index received' (duration: 48.10065ms)","trace[233576665] 'applied index is now lower than readState.Index' (duration: 229.14055ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:30:45.661993Z","caller":"traceutil/trace.go:171","msg":"trace[1101464865] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"871.291179ms","start":"2026-04-23T12:30:44.790692Z","end":"2026-04-23T12:30:45.661983Z","steps":["trace[1101464865] 'process raft request' (duration: 642.126899ms)","trace[1101464865] 'compare' (duration: 228.504572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:30:45.662056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:44.790662Z","time spent":"871.366832ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T12:30:45.662274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.677172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T12:30:45.662317Z","caller":"traceutil/trace.go:171","msg":"trace[1676824724] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1656; }","duration":"277.772714ms","start":"2026-04-23T12:30:45.384538Z","end":"2026-04-23T12:30:45.66231Z","steps":["trace[1676824724] 'agreement among raft nodes before linearized reading' (duration: 277.699792ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:45.748868Z","caller":"traceutil/trace.go:171","msg":"trace[1833022429] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"253.754191ms","start":"2026-04-23T12:30:45.495073Z","end":"2026-04-23T12:30:45.748827Z","steps":["trace[1833022429] 'process raft request' (duration: 253.497954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:45.748976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.430859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T12:30:45.749089Z","caller":"traceutil/trace.go:171","msg":"trace[1449771191] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1657; }","duration":"311.640535ms","start":"2026-04-23T12:30:45.437426Z","end":"2026-04-23T12:30:45.749066Z","steps":["trace[1449771191] 'agreement among raft nodes before linearized reading' (duration: 311.410588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:45.749106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.710384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:30:45.74924Z","caller":"traceutil/trace.go:171","msg":"trace[1127128835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1657; }","duration":"185.046493ms","start":"2026-04-23T12:30:45.564148Z","end":"2026-04-23T12:30:45.749195Z","steps":["trace[1127128835] 'agreement among raft nodes before linearized reading' (duration: 184.671702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:45.749176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:45.437391Z","time spent":"311.768068ms","remote":"127.0.0.1:33140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-23T12:30:46.103252Z","caller":"traceutil/trace.go:171","msg":"trace[494249074] transaction","detail":"{read_only:false; response_revision:1660; number_of_response:1; }","duration":"252.262619ms","start":"2026-04-23T12:30:45.850962Z","end":"2026-04-23T12:30:46.103225Z","steps":["trace[494249074] 'process raft request' (duration: 251.660543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:58.161911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.5655ms","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-23T12:30:58.162014Z","caller":"traceutil/trace.go:171","msg":"trace[1407597445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1749; }","duration":"230.735306ms","start":"2026-04-23T12:30:57.931263Z","end":"2026-04-23T12:30:58.161999Z","steps":["trace[1407597445] 'range keys from in-memory index tree' (duration: 230.339634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:30:58.161911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.640352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-23T12:30:58.161911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.522009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.90\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T12:30:58.162126Z","caller":"traceutil/trace.go:171","msg":"trace[1038922177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1749; }","duration":"266.89411ms","start":"2026-04-23T12:30:57.89519Z","end":"2026-04-23T12:30:58.162084Z","steps":["trace[1038922177] 'range keys from in-memory index tree' (duration: 266.425677ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:58.162188Z","caller":"traceutil/trace.go:171","msg":"trace[1537373433] range","detail":"{range_begin:/registry/masterleases/199.19.213.90; range_end:; response_count:1; response_revision:1749; }","duration":"178.867088ms","start":"2026-04-23T12:30:57.983308Z","end":"2026-04-23T12:30:58.162175Z","steps":["trace[1537373433] 'range keys from in-memory index tree' (duration: 178.21398ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:58.329605Z","caller":"traceutil/trace.go:171","msg":"trace[1430867051] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"163.658483ms","start":"2026-04-23T12:30:58.165925Z","end":"2026-04-23T12:30:58.329583Z","steps":["trace[1430867051] 'process raft request' (duration: 163.619362ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:58.329674Z","caller":"traceutil/trace.go:171","msg":"trace[711976796] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"163.965881ms","start":"2026-04-23T12:30:58.16566Z","end":"2026-04-23T12:30:58.329626Z","steps":["trace[711976796] 'process raft request' (duration: 163.709064ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:30:58.329777Z","caller":"traceutil/trace.go:171","msg":"trace[1552663837] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"163.995272ms","start":"2026-04-23T12:30:58.165775Z","end":"2026-04-23T12:30:58.32977Z","steps":["trace[1552663837] 'process raft request' (duration: 163.740774ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:00.415714Z","caller":"traceutil/trace.go:171","msg":"trace[527967542] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"562.921057ms","start":"2026-04-23T12:30:59.852766Z","end":"2026-04-23T12:31:00.415687Z","steps":["trace[527967542] 'process raft request' (duration: 562.771383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:00.415899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:59.852749Z","time spent":"563.04938ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:31:00.874393Z","caller":"traceutil/trace.go:171","msg":"trace[140060499] linearizableReadLoop","detail":"{readStateIndex:1838; appliedIndex:1836; }","duration":"539.213181ms","start":"2026-04-23T12:31:00.335136Z","end":"2026-04-23T12:31:00.874349Z","steps":["trace[140060499] 'read index received' (duration: 80.330084ms)","trace[140060499] 'applied index is now lower than readState.Index' (duration: 458.881267ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:31:00.874408Z","caller":"traceutil/trace.go:171","msg":"trace[1890368294] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"946.305243ms","start":"2026-04-23T12:30:59.92806Z","end":"2026-04-23T12:31:00.874366Z","steps":["trace[1890368294] 'process raft request' (duration: 945.260164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:00.874582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.416146ms","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-23T12:31:00.874625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:30:59.928026Z","time spent":"946.48823ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:31:00.874648Z","caller":"traceutil/trace.go:171","msg":"trace[2144745233] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1757; }","duration":"539.523149ms","start":"2026-04-23T12:31:00.335106Z","end":"2026-04-23T12:31:00.874629Z","steps":["trace[2144745233] 'agreement among raft nodes before linearized reading' (duration: 539.263641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:00.874686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:00.33509Z","time spent":"539.589061ms","remote":"127.0.0.1:33046","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-23T12:31:00.874712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.55618ms","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-23T12:31:00.874787Z","caller":"traceutil/trace.go:171","msg":"trace[504254166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1757; }","duration":"539.658692ms","start":"2026-04-23T12:31:00.335106Z","end":"2026-04-23T12:31:00.874764Z","steps":["trace[504254166] 'agreement among raft nodes before linearized reading' (duration: 539.389595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:00.874802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.580531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-23T12:31:00.874833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:00.33509Z","time spent":"539.731366ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T12:31:00.874862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.028005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:31:00.87487Z","caller":"traceutil/trace.go:171","msg":"trace[1103292164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1757; }","duration":"539.658403ms","start":"2026-04-23T12:31:00.33519Z","end":"2026-04-23T12:31:00.874849Z","steps":["trace[1103292164] 'agreement among raft nodes before linearized reading' (duration: 539.395135ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:00.874898Z","caller":"traceutil/trace.go:171","msg":"trace[966776853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1757; }","duration":"312.114298ms","start":"2026-04-23T12:31:00.562773Z","end":"2026-04-23T12:31:00.874888Z","steps":["trace[966776853] 'agreement among raft nodes before linearized reading' (duration: 312.046576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:00.87492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:00.335123Z","time spent":"539.785086ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T12:31:00.874929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:00.562725Z","time spent":"312.19793ms","remote":"127.0.0.1:32826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T12:31:00.875036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.350638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T12:31:00.875145Z","caller":"traceutil/trace.go:171","msg":"trace[937076639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1757; }","duration":"243.522413ms","start":"2026-04-23T12:31:00.631595Z","end":"2026-04-23T12:31:00.875118Z","steps":["trace[937076639] 'agreement among raft nodes before linearized reading' (duration: 243.196764ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:13.275215Z","caller":"traceutil/trace.go:171","msg":"trace[230381703] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"331.46005ms","start":"2026-04-23T12:31:12.943732Z","end":"2026-04-23T12:31:13.275192Z","steps":["trace[230381703] 'process raft request' (duration: 331.305206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:13.275396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:12.943721Z","time spent":"331.588384ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:31:13.275809Z","caller":"traceutil/trace.go:171","msg":"trace[51756229] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1881; }","duration":"249.250834ms","start":"2026-04-23T12:31:13.026547Z","end":"2026-04-23T12:31:13.275798Z","steps":["trace[51756229] 'read index received' (duration: 249.246554ms)","trace[51756229] 'applied index is now lower than readState.Index' (duration: 3.49µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:31:13.275948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.550931ms","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-23T12:31:13.275975Z","caller":"traceutil/trace.go:171","msg":"trace[1194926471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1798; }","duration":"332.600122ms","start":"2026-04-23T12:31:12.943367Z","end":"2026-04-23T12:31:13.275967Z","steps":["trace[1194926471] 'agreement among raft nodes before linearized reading' (duration: 332.478569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:13.276002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:12.943356Z","time spent":"332.639733ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T12:31:13.276237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.230933ms","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-23T12:31:13.276291Z","caller":"traceutil/trace.go:171","msg":"trace[1611910260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1798; }","duration":"296.335414ms","start":"2026-04-23T12:31:12.979943Z","end":"2026-04-23T12:31:13.276279Z","steps":["trace[1611910260] 'agreement among raft nodes before linearized reading' (duration: 296.1596ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:13.295785Z","caller":"traceutil/trace.go:171","msg":"trace[858945780] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"264.842ms","start":"2026-04-23T12:31:13.030918Z","end":"2026-04-23T12:31:13.29576Z","steps":["trace[858945780] 'process raft request' (duration: 264.705587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:14.067511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.344125ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-23T12:31:14.06763Z","caller":"traceutil/trace.go:171","msg":"trace[715281737] linearizableReadLoop","detail":"{readStateIndex:1885; appliedIndex:1884; }","duration":"503.748196ms","start":"2026-04-23T12:31:13.563862Z","end":"2026-04-23T12:31:14.06761Z","steps":["trace[715281737] 'read index received' (duration: 263.34897ms)","trace[715281737] 'applied index is now lower than readState.Index' (duration: 240.397216ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:31:14.067719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.843939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:31:14.06776Z","caller":"traceutil/trace.go:171","msg":"trace[279160680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1801; }","duration":"503.920991ms","start":"2026-04-23T12:31:13.563826Z","end":"2026-04-23T12:31:14.067747Z","steps":["trace[279160680] 'agreement among raft nodes before linearized reading' (duration: 503.841099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:14.06781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:13.563809Z","time spent":"503.991804ms","remote":"127.0.0.1:32826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T12:31:14.716834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.027595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:31:14.71692Z","caller":"traceutil/trace.go:171","msg":"trace[545852888] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1802; }","duration":"132.165038ms","start":"2026-04-23T12:31:14.584738Z","end":"2026-04-23T12:31:14.716903Z","steps":["trace[545852888] 'count revisions from in-memory index tree' (duration: 131.848629ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:15.48777Z","caller":"traceutil/trace.go:171","msg":"trace[1949430603] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1886; }","duration":"207.568266ms","start":"2026-04-23T12:31:15.280176Z","end":"2026-04-23T12:31:15.487744Z","steps":["trace[1949430603] 'read index received' (duration: 207.39756ms)","trace[1949430603] 'applied index is now lower than readState.Index' (duration: 169.115µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:31:15.488576Z","caller":"traceutil/trace.go:171","msg":"trace[558492770] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"453.197448ms","start":"2026-04-23T12:31:15.035363Z","end":"2026-04-23T12:31:15.48856Z","steps":["trace[558492770] 'process raft request' (duration: 452.19221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:15.488771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:15.035346Z","time spent":"453.27379ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T12:31:15.489018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.841661ms","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-23T12:31:15.489084Z","caller":"traceutil/trace.go:171","msg":"trace[1234027197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1803; }","duration":"208.955423ms","start":"2026-04-23T12:31:15.280118Z","end":"2026-04-23T12:31:15.489073Z","steps":["trace[1234027197] 'agreement among raft nodes before linearized reading' (duration: 208.788319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:15.489214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.171898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-23T12:31:15.489238Z","caller":"traceutil/trace.go:171","msg":"trace[1982653300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1803; }","duration":"189.196939ms","start":"2026-04-23T12:31:15.300034Z","end":"2026-04-23T12:31:15.489231Z","steps":["trace[1982653300] 'agreement among raft nodes before linearized reading' (duration: 189.126367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:15.489538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.105124ms","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-23T12:31:15.489623Z","caller":"traceutil/trace.go:171","msg":"trace[1970448647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1803; }","duration":"175.253707ms","start":"2026-04-23T12:31:15.314346Z","end":"2026-04-23T12:31:15.4896Z","steps":["trace[1970448647] 'agreement among raft nodes before linearized reading' (duration: 175.019551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:15.489716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.37004ms","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-23T12:31:15.489752Z","caller":"traceutil/trace.go:171","msg":"trace[1190222484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1803; }","duration":"175.458323ms","start":"2026-04-23T12:31:15.314284Z","end":"2026-04-23T12:31:15.489742Z","steps":["trace[1190222484] 'agreement among raft nodes before linearized reading' (duration: 175.371291ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:18.62741Z","caller":"traceutil/trace.go:171","msg":"trace[712903850] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"134.274878ms","start":"2026-04-23T12:31:18.49309Z","end":"2026-04-23T12:31:18.627364Z","steps":["trace[712903850] 'process raft request' (duration: 133.930768ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:19.951154Z","caller":"traceutil/trace.go:171","msg":"trace[1455128515] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"448.575059ms","start":"2026-04-23T12:31:19.502555Z","end":"2026-04-23T12:31:19.95113Z","steps":["trace[1455128515] 'read index received' (duration: 448.364753ms)","trace[1455128515] 'applied index is now lower than readState.Index' (duration: 209.436µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:31:19.95126Z","caller":"traceutil/trace.go:171","msg":"trace[1511020550] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"601.032166ms","start":"2026-04-23T12:31:19.350176Z","end":"2026-04-23T12:31:19.951208Z","steps":["trace[1511020550] 'process raft request' (duration: 600.801709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:19.951424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.914031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-23T12:31:19.95147Z","caller":"traceutil/trace.go:171","msg":"trace[980519898] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1843; }","duration":"436.997913ms","start":"2026-04-23T12:31:19.514461Z","end":"2026-04-23T12:31:19.951459Z","steps":["trace[980519898] 'agreement among raft nodes before linearized reading' (duration: 436.814608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:19.951509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:19.514448Z","time spent":"437.051855ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T12:31:19.951524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:19.35016Z","time spent":"601.221911ms","remote":"127.0.0.1:32864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T12:31:19.95157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.825156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T12:31:19.951631Z","caller":"traceutil/trace.go:171","msg":"trace[1786978336] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1843; }","duration":"232.011871ms","start":"2026-04-23T12:31:19.719601Z","end":"2026-04-23T12:31:19.951613Z","steps":["trace[1786978336] 'agreement among raft nodes before linearized reading' (duration: 231.935269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:19.952019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.074587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-23T12:31:19.951485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.330287ms","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-23T12:31:19.952077Z","caller":"traceutil/trace.go:171","msg":"trace[406076807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1843; }","duration":"436.14774ms","start":"2026-04-23T12:31:19.515919Z","end":"2026-04-23T12:31:19.952067Z","steps":["trace[406076807] 'agreement among raft nodes before linearized reading' (duration: 436.020806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:19.952109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:19.515913Z","time spent":"436.188471ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T12:31:19.952106Z","caller":"traceutil/trace.go:171","msg":"trace[1577430551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1843; }","duration":"436.017935ms","start":"2026-04-23T12:31:19.516074Z","end":"2026-04-23T12:31:19.952092Z","steps":["trace[1577430551] 'agreement among raft nodes before linearized reading' (duration: 435.232253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:19.952105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.516594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-23T12:31:19.952179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:19.516042Z","time spent":"436.116958ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T12:31:19.95222Z","caller":"traceutil/trace.go:171","msg":"trace[2037328940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1843; }","duration":"449.700319ms","start":"2026-04-23T12:31:19.502495Z","end":"2026-04-23T12:31:19.952195Z","steps":["trace[2037328940] 'agreement among raft nodes before linearized reading' (duration: 448.759583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:19.952455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.552932ms","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-23T12:31:19.952507Z","caller":"traceutil/trace.go:171","msg":"trace[1842418434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1843; }","duration":"436.623183ms","start":"2026-04-23T12:31:19.515873Z","end":"2026-04-23T12:31:19.952496Z","steps":["trace[1842418434] 'agreement among raft nodes before linearized reading' (duration: 436.412257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:19.952588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:19.515861Z","time spent":"436.676074ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T12:31:19.952674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:19.502463Z","time spent":"449.815224ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T12:31:20.347694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.898844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T12:31:20.347808Z","caller":"traceutil/trace.go:171","msg":"trace[937979909] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"391.305129ms","start":"2026-04-23T12:31:19.956482Z","end":"2026-04-23T12:31:20.347787Z","steps":["trace[937979909] 'process raft request' (duration: 127.174238ms)","trace[937979909] 'compare' (duration: 263.774101ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:31:20.347878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:19.956456Z","time spent":"391.386251ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:31:20.3486Z","caller":"traceutil/trace.go:171","msg":"trace[1262362601] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"391.34086ms","start":"2026-04-23T12:31:19.957236Z","end":"2026-04-23T12:31:20.348577Z","steps":["trace[1262362601] 'process raft request' (duration: 390.829925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:20.348756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:19.957222Z","time spent":"391.429042ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:31:20.348862Z","caller":"traceutil/trace.go:171","msg":"trace[1920964186] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"389.724075ms","start":"2026-04-23T12:31:19.959119Z","end":"2026-04-23T12:31:20.348843Z","steps":["trace[1920964186] 'process raft request' (duration: 389.134038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:20.34895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:19.959106Z","time spent":"389.804596ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T12:31:20.348962Z","caller":"traceutil/trace.go:171","msg":"trace[293107449] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"389.993262ms","start":"2026-04-23T12:31:19.958894Z","end":"2026-04-23T12:31:20.348887Z","steps":["trace[293107449] 'process raft request' (duration: 389.285862ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:20.349161Z","caller":"traceutil/trace.go:171","msg":"trace[1335456145] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"390.078724ms","start":"2026-04-23T12:31:19.959052Z","end":"2026-04-23T12:31:20.349131Z","steps":["trace[1335456145] 'process raft request' (duration: 389.159139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:20.349306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:19.959021Z","time spent":"390.203828ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T12:31:20.349309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:19.95886Z","time spent":"390.301381ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T12:31:21.606358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.121752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T12:31:21.606514Z","caller":"traceutil/trace.go:171","msg":"trace[1449295954] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1850; }","duration":"505.322278ms","start":"2026-04-23T12:31:21.101159Z","end":"2026-04-23T12:31:21.606481Z","steps":["trace[1449295954] 'count revisions from in-memory index tree' (duration: 505.020649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:21.606593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:21.101101Z","time spent":"505.470031ms","remote":"127.0.0.1:33310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-23T12:31:21.606632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.516242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:31:21.606741Z","caller":"traceutil/trace.go:171","msg":"trace[1016047659] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1850; }","duration":"444.732148ms","start":"2026-04-23T12:31:21.161985Z","end":"2026-04-23T12:31:21.606717Z","steps":["trace[1016047659] 'count revisions from in-memory index tree' (duration: 444.384508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:21.606796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:21.161953Z","time spent":"444.82944ms","remote":"127.0.0.1:60844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-23T12:31:21.606773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.804261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:31:21.606912Z","caller":"traceutil/trace.go:171","msg":"trace[1050889769] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1850; }","duration":"186.991677ms","start":"2026-04-23T12:31:21.419862Z","end":"2026-04-23T12:31:21.606854Z","steps":["trace[1050889769] 'count revisions from in-memory index tree' (duration: 186.674347ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:22.476549Z","caller":"traceutil/trace.go:171","msg":"trace[1612240143] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"114.148183ms","start":"2026-04-23T12:31:22.362367Z","end":"2026-04-23T12:31:22.476516Z","steps":["trace[1612240143] 'process raft request' (duration: 114.092171ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:22.477103Z","caller":"traceutil/trace.go:171","msg":"trace[564064618] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"116.345845ms","start":"2026-04-23T12:31:22.360659Z","end":"2026-04-23T12:31:22.477005Z","steps":["trace[564064618] 'process raft request' (duration: 115.711047ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:22.476795Z","caller":"traceutil/trace.go:171","msg":"trace[1378495353] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"116.358464ms","start":"2026-04-23T12:31:22.360394Z","end":"2026-04-23T12:31:22.476753Z","steps":["trace[1378495353] 'process raft request' (duration: 115.767128ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:22.477426Z","caller":"traceutil/trace.go:171","msg":"trace[1405987461] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"116.388495ms","start":"2026-04-23T12:31:22.36102Z","end":"2026-04-23T12:31:22.477409Z","steps":["trace[1405987461] 'process raft request' (duration: 115.397057ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:23.173515Z","caller":"traceutil/trace.go:171","msg":"trace[897460828] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"114.620117ms","start":"2026-04-23T12:31:23.058859Z","end":"2026-04-23T12:31:23.173479Z","steps":["trace[897460828] 'process raft request' (duration: 99.966246ms)","trace[897460828] 'compare' (duration: 14.109296ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:31:23.60171Z","caller":"traceutil/trace.go:171","msg":"trace[1428612577] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1943; }","duration":"437.941138ms","start":"2026-04-23T12:31:23.163733Z","end":"2026-04-23T12:31:23.601674Z","steps":["trace[1428612577] 'read index received' (duration: 341.696277ms)","trace[1428612577] 'applied index is now lower than readState.Index' (duration: 96.242811ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T12:31:23.601791Z","caller":"traceutil/trace.go:171","msg":"trace[806721712] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"382.295075ms","start":"2026-04-23T12:31:23.219462Z","end":"2026-04-23T12:31:23.601757Z","steps":["trace[806721712] 'process raft request' (duration: 382.087979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:23.601904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.158794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:31:23.601952Z","caller":"traceutil/trace.go:171","msg":"trace[1763515480] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1859; }","duration":"438.252545ms","start":"2026-04-23T12:31:23.163689Z","end":"2026-04-23T12:31:23.601941Z","steps":["trace[1763515480] 'agreement among raft nodes before linearized reading' (duration: 438.092901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:23.601945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:23.219426Z","time spent":"382.44525ms","remote":"127.0.0.1:32864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T12:31:23.60198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:23.163669Z","time spent":"438.305837ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-23T12:31:23.602303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.27607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-04-23T12:31:23.602369Z","caller":"traceutil/trace.go:171","msg":"trace[1622530435] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1859; }","duration":"220.409865ms","start":"2026-04-23T12:31:23.381945Z","end":"2026-04-23T12:31:23.602355Z","steps":["trace[1622530435] 'agreement among raft nodes before linearized reading' (duration: 220.182169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:23.602411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.75456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-23T12:31:23.602484Z","caller":"traceutil/trace.go:171","msg":"trace[2090724657] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1859; }","duration":"428.833452ms","start":"2026-04-23T12:31:23.173629Z","end":"2026-04-23T12:31:23.602463Z","steps":["trace[2090724657] 'agreement among raft nodes before linearized reading' (duration: 428.256196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:23.602539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:23.173573Z","time spent":"428.947605ms","remote":"127.0.0.1:33368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":141,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-23T12:31:23.747004Z","caller":"traceutil/trace.go:171","msg":"trace[1624441113] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"139.744651ms","start":"2026-04-23T12:31:23.607231Z","end":"2026-04-23T12:31:23.746975Z","steps":["trace[1624441113] 'process raft request' (duration: 139.690439ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:23.747116Z","caller":"traceutil/trace.go:171","msg":"trace[1445497770] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"140.42513ms","start":"2026-04-23T12:31:23.606653Z","end":"2026-04-23T12:31:23.747078Z","steps":["trace[1445497770] 'process raft request' (duration: 78.187414ms)","trace[1445497770] 'compare' (duration: 61.459785ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T12:31:40.060636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.599221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-23T12:31:40.060726Z","caller":"traceutil/trace.go:171","msg":"trace[1663030401] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1982; }","duration":"129.740585ms","start":"2026-04-23T12:31:39.930965Z","end":"2026-04-23T12:31:40.060706Z","steps":["trace[1663030401] 'range keys from in-memory index tree' (duration: 129.402205ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:45.951469Z","caller":"traceutil/trace.go:171","msg":"trace[1328628388] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"166.683988ms","start":"2026-04-23T12:31:45.784564Z","end":"2026-04-23T12:31:45.951248Z","steps":["trace[1328628388] 'process raft request' (duration: 165.287671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:46.746569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.065012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-23T12:31:46.74665Z","caller":"traceutil/trace.go:171","msg":"trace[1130568657] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2015; }","duration":"400.206836ms","start":"2026-04-23T12:31:46.346425Z","end":"2026-04-23T12:31:46.746632Z","steps":["trace[1130568657] 'range keys from in-memory index tree' (duration: 399.888438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:46.746688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:46.346393Z","time spent":"400.285388ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-23T12:31:46.746756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.420242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-23T12:31:46.746876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.861181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T12:31:46.746917Z","caller":"traceutil/trace.go:171","msg":"trace[397533120] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2015; }","duration":"140.900523ms","start":"2026-04-23T12:31:46.606007Z","end":"2026-04-23T12:31:46.746908Z","steps":["trace[397533120] 'range keys from in-memory index tree' (duration: 140.786268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:46.747076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.235833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-23T12:31:46.747092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.742118ms","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-23T12:31:46.74712Z","caller":"traceutil/trace.go:171","msg":"trace[400577493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2015; }","duration":"250.325426ms","start":"2026-04-23T12:31:46.496785Z","end":"2026-04-23T12:31:46.74711Z","steps":["trace[400577493] 'range keys from in-memory index tree' (duration: 250.066859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:46.747186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.855251ms","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-23T12:31:46.74722Z","caller":"traceutil/trace.go:171","msg":"trace[610004153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2015; }","duration":"125.913973ms","start":"2026-04-23T12:31:46.621299Z","end":"2026-04-23T12:31:46.747213Z","steps":["trace[610004153] 'range keys from in-memory index tree' (duration: 125.778719ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:46.746871Z","caller":"traceutil/trace.go:171","msg":"trace[981813163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2015; }","duration":"145.578667ms","start":"2026-04-23T12:31:46.601267Z","end":"2026-04-23T12:31:46.746845Z","steps":["trace[981813163] 'range keys from in-memory index tree' (duration: 145.224027ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T12:31:46.747124Z","caller":"traceutil/trace.go:171","msg":"trace[672310737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2015; }","duration":"137.786969ms","start":"2026-04-23T12:31:46.609329Z","end":"2026-04-23T12:31:46.747115Z","steps":["trace[672310737] 'range keys from in-memory index tree' (duration: 137.670725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:46.747742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.520408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T12:31:46.747789Z","caller":"traceutil/trace.go:171","msg":"trace[1753662478] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2015; }","duration":"482.621191ms","start":"2026-04-23T12:31:46.265155Z","end":"2026-04-23T12:31:46.747776Z","steps":["trace[1753662478] 'count revisions from in-memory index tree' (duration: 482.432215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T12:31:46.747832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T12:31:46.265126Z","time spent":"482.694643ms","remote":"127.0.0.1:60848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "}