{"level":"warn","ts":"2026-05-19T13:35:51.627487Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T13:35:51.627577Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.200: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.200:2380","--initial-cluster=instance=https://199.19.213.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.200: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-05-19T13:35:51.627693Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T13:35:51.627707Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-05-19T13:35:51.627724Z","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-05-19T13:35:51.62857Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"]} {"level":"info","ts":"2026-05-19T13:35:51.628651Z","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.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.200: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-05-19T13:35:51.632186Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.107944ms"} {"level":"info","ts":"2026-05-19T13:35:51.641074Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3363378c2227fa42","cluster-id":"7faf957b2e4cf761"} {"level":"info","ts":"2026-05-19T13:35:51.641156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T13:35:51.641207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 0"} {"level":"info","ts":"2026-05-19T13:35:51.641224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3363378c2227fa42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T13:35:51.641237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 1"} {"level":"info","ts":"2026-05-19T13:35:51.641303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"warn","ts":"2026-05-19T13:35:51.647405Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T13:35:51.648498Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T13:35:51.649453Z","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-05-19T13:35:51.651288Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3363378c2227fa42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T13:35:51.651515Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3363378c2227fa42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T13:35:51.651522Z","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-05-19T13:35:51.651671Z","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-05-19T13:35:51.651709Z","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-05-19T13:35:51.653585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"info","ts":"2026-05-19T13:35:51.653882Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","added-peer-id":"3363378c2227fa42","added-peer-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-05-19T13:35:51.658493Z","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-05-19T13:35:51.658568Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-05-19T13:35:51.658604Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-05-19T13:35:51.658864Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T13:35:51.658809Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3363378c2227fa42","initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T13:35:52.641819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T13:35:52.641879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T13:35:52.64192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgPreVoteResp from 3363378c2227fa42 at term 1"} {"level":"info","ts":"2026-05-19T13:35:52.641942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became candidate at term 2"} {"level":"info","ts":"2026-05-19T13:35:52.641953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgVoteResp from 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-05-19T13:35:52.641969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became leader at term 2"} {"level":"info","ts":"2026-05-19T13:35:52.641988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3363378c2227fa42 elected leader 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-05-19T13:35:52.64295Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:35:52.644131Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:35:52.644121Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3363378c2227fa42","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.200:2379]}","request-path":"/0/members/3363378c2227fa42/attributes","cluster-id":"7faf957b2e4cf761","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T13:35:52.644515Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T13:35:52.644581Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T13:35:52.644542Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:35:52.644637Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:35:52.644773Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:35:52.644864Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:35:52.648296Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T13:35:52.648459Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.200:2379"} {"level":"info","ts":"2026-05-19T13:35:54.98901Z","caller":"traceutil/trace.go:171","msg":"trace[1191337801] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"110.735316ms","start":"2026-05-19T13:35:54.878247Z","end":"2026-05-19T13:35:54.988982Z","steps":["trace[1191337801] 'read index received' (duration: 110.460551ms)","trace[1191337801] 'applied index is now lower than readState.Index' (duration: 273.425µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:35:54.989116Z","caller":"traceutil/trace.go:171","msg":"trace[977514430] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"111.219636ms","start":"2026-05-19T13:35:54.877869Z","end":"2026-05-19T13:35:54.989089Z","steps":["trace[977514430] 'process raft request' (duration: 110.985541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:35:54.989371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.075043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T13:35:54.989446Z","caller":"traceutil/trace.go:171","msg":"trace[620815922] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:71; }","duration":"111.239087ms","start":"2026-05-19T13:35:54.878191Z","end":"2026-05-19T13:35:54.98943Z","steps":["trace[620815922] 'agreement among raft nodes before linearized reading' (duration: 110.956431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:35:55.525307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.358482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-19T13:35:55.525422Z","caller":"traceutil/trace.go:171","msg":"trace[786037298] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"529.090373ms","start":"2026-05-19T13:35:54.996313Z","end":"2026-05-19T13:35:55.525403Z","steps":["trace[786037298] 'read index received' (duration: 264.128558ms)","trace[786037298] 'applied index is now lower than readState.Index' (duration: 264.959955ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:35:55.525529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.214895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T13:35:55.525585Z","caller":"traceutil/trace.go:171","msg":"trace[1075187559] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:72; }","duration":"529.303236ms","start":"2026-05-19T13:35:54.996269Z","end":"2026-05-19T13:35:55.525572Z","steps":["trace[1075187559] 'agreement among raft nodes before linearized reading' (duration: 529.190824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:35:55.525638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:35:54.99625Z","time spent":"529.375768ms","remote":"127.0.0.1:50790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"info","ts":"2026-05-19T13:35:55.525483Z","caller":"traceutil/trace.go:171","msg":"trace[375482715] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"530.912849ms","start":"2026-05-19T13:35:54.994529Z","end":"2026-05-19T13:35:55.525442Z","steps":["trace[375482715] 'process raft request' (duration: 265.868723ms)","trace[375482715] 'compare' (duration: 264.22982ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:35:55.525834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:35:54.994517Z","time spent":"531.253108ms","remote":"127.0.0.1:50808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:36:23.11582Z","caller":"traceutil/trace.go:171","msg":"trace[1753453806] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"211.349371ms","start":"2026-05-19T13:36:22.904444Z","end":"2026-05-19T13:36:23.115793Z","steps":["trace[1753453806] 'process raft request' (duration: 211.201138ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:23.122431Z","caller":"traceutil/trace.go:171","msg":"trace[1884766437] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"208.312075ms","start":"2026-05-19T13:36:22.914097Z","end":"2026-05-19T13:36:23.122409Z","steps":["trace[1884766437] 'process raft request' (duration: 207.842124ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:23.122891Z","caller":"traceutil/trace.go:171","msg":"trace[830959430] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"201.059205ms","start":"2026-05-19T13:36:22.921821Z","end":"2026-05-19T13:36:23.12288Z","steps":["trace[830959430] 'process raft request' (duration: 200.412811ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:23.123013Z","caller":"traceutil/trace.go:171","msg":"trace[1162831920] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"208.306045ms","start":"2026-05-19T13:36:22.914691Z","end":"2026-05-19T13:36:23.122997Z","steps":["trace[1162831920] 'process raft request' (duration: 207.355404ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:23.123143Z","caller":"traceutil/trace.go:171","msg":"trace[793550738] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"201.904333ms","start":"2026-05-19T13:36:22.92123Z","end":"2026-05-19T13:36:23.123135Z","steps":["trace[793550738] 'process raft request' (duration: 200.952252ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:23.123325Z","caller":"traceutil/trace.go:171","msg":"trace[32388711] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"195.771768ms","start":"2026-05-19T13:36:22.927535Z","end":"2026-05-19T13:36:23.123307Z","steps":["trace[32388711] 'process raft request' (duration: 195.045272ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:23.123417Z","caller":"traceutil/trace.go:171","msg":"trace[767518165] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"177.716811ms","start":"2026-05-19T13:36:22.94569Z","end":"2026-05-19T13:36:23.123407Z","steps":["trace[767518165] 'process raft request' (duration: 177.125608ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:23.123432Z","caller":"traceutil/trace.go:171","msg":"trace[484297043] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"197.543767ms","start":"2026-05-19T13:36:22.925878Z","end":"2026-05-19T13:36:23.123422Z","steps":["trace[484297043] 'process raft request' (duration: 196.437113ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:23.123467Z","caller":"traceutil/trace.go:171","msg":"trace[2075005581] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"196.410912ms","start":"2026-05-19T13:36:22.927037Z","end":"2026-05-19T13:36:23.123448Z","steps":["trace[2075005581] 'process raft request' (duration: 195.448301ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:23.123504Z","caller":"traceutil/trace.go:171","msg":"trace[434098431] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"146.372211ms","start":"2026-05-19T13:36:22.977122Z","end":"2026-05-19T13:36:23.123495Z","steps":["trace[434098431] 'process raft request' (duration: 145.777018ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:23.123569Z","caller":"traceutil/trace.go:171","msg":"trace[808660454] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"192.971167ms","start":"2026-05-19T13:36:22.930464Z","end":"2026-05-19T13:36:23.123435Z","steps":["trace[808660454] 'process raft request' (duration: 192.233981ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:27.196362Z","caller":"traceutil/trace.go:171","msg":"trace[1155665648] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"231.892483ms","start":"2026-05-19T13:36:26.964447Z","end":"2026-05-19T13:36:27.196339Z","steps":["trace[1155665648] 'process raft request' (duration: 231.456544ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:29.897678Z","caller":"traceutil/trace.go:171","msg":"trace[1585176065] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"111.382181ms","start":"2026-05-19T13:36:29.786246Z","end":"2026-05-19T13:36:29.897628Z","steps":["trace[1585176065] 'process raft request' (duration: 111.210318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:36:30.131747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.083333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:36:30.131849Z","caller":"traceutil/trace.go:171","msg":"trace[36445342] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"226.73962ms","start":"2026-05-19T13:36:29.905093Z","end":"2026-05-19T13:36:30.131832Z","steps":["trace[36445342] 'read index received' (duration: 75.389439ms)","trace[36445342] 'applied index is now lower than readState.Index' (duration: 151.348791ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:36:30.131891Z","caller":"traceutil/trace.go:171","msg":"trace[1909545812] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"227.953697ms","start":"2026-05-19T13:36:29.903915Z","end":"2026-05-19T13:36:30.131869Z","steps":["trace[1909545812] 'process raft request' (duration: 97.517786ms)","trace[1909545812] 'compare' (duration: 129.971571ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:36:30.131952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.860132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-19T13:36:30.131981Z","caller":"traceutil/trace.go:171","msg":"trace[906689742] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:585; }","duration":"226.905823ms","start":"2026-05-19T13:36:29.905065Z","end":"2026-05-19T13:36:30.131971Z","steps":["trace[906689742] 'agreement among raft nodes before linearized reading' (duration: 226.847032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:36:30.132179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.835953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ff56z\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-19T13:36:30.132284Z","caller":"traceutil/trace.go:171","msg":"trace[1532699894] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ff56z; range_end:; response_count:1; response_revision:585; }","duration":"142.976847ms","start":"2026-05-19T13:36:29.989272Z","end":"2026-05-19T13:36:30.132249Z","steps":["trace[1532699894] 'agreement among raft nodes before linearized reading' (duration: 142.710021ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:30.667264Z","caller":"traceutil/trace.go:171","msg":"trace[1350896550] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"520.511165ms","start":"2026-05-19T13:36:30.146725Z","end":"2026-05-19T13:36:30.667237Z","steps":["trace[1350896550] 'process raft request' (duration: 442.169211ms)","trace[1350896550] 'compare' (duration: 78.030757ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:36:30.667448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:36:30.146705Z","time spent":"520.653688ms","remote":"127.0.0.1:50616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:36:30.947077Z","caller":"traceutil/trace.go:171","msg":"trace[1333834726] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"774.960984ms","start":"2026-05-19T13:36:30.172077Z","end":"2026-05-19T13:36:30.947038Z","steps":["trace[1333834726] 'process raft request' (duration: 772.48747ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:30.94713Z","caller":"traceutil/trace.go:171","msg":"trace[363224407] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:612; }","duration":"339.507591ms","start":"2026-05-19T13:36:30.607597Z","end":"2026-05-19T13:36:30.947104Z","steps":["trace[363224407] 'read index received' (duration: 336.917954ms)","trace[363224407] 'applied index is now lower than readState.Index' (duration: 2.588047ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:36:30.94723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:36:30.172052Z","time spent":"775.108847ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:36:30.947299Z","caller":"traceutil/trace.go:171","msg":"trace[793735240] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"772.921108ms","start":"2026-05-19T13:36:30.174354Z","end":"2026-05-19T13:36:30.947275Z","steps":["trace[793735240] 'process raft request' (duration: 772.704804ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:30.947247Z","caller":"traceutil/trace.go:171","msg":"trace[779810633] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"775.094136ms","start":"2026-05-19T13:36:30.172143Z","end":"2026-05-19T13:36:30.947237Z","steps":["trace[779810633] 'process raft request' (duration: 774.79933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:36:30.947412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.804448ms","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-05-19T13:36:30.947486Z","caller":"traceutil/trace.go:171","msg":"trace[875959408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"339.928261ms","start":"2026-05-19T13:36:30.607546Z","end":"2026-05-19T13:36:30.947474Z","steps":["trace[875959408] 'agreement among raft nodes before linearized reading' (duration: 339.719436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:36:30.947579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:36:30.607526Z","time spent":"340.041753ms","remote":"127.0.0.1:50710","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":"info","ts":"2026-05-19T13:36:30.947611Z","caller":"traceutil/trace.go:171","msg":"trace[1628796830] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"774.604596ms","start":"2026-05-19T13:36:30.172988Z","end":"2026-05-19T13:36:30.947592Z","steps":["trace[1628796830] 'process raft request' (duration: 774.018253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:36:30.94744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:36:30.174334Z","time spent":"773.028631ms","remote":"127.0.0.1:50616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:36:30.947479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:36:30.172115Z","time spent":"775.335422ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T13:36:30.947749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:36:30.172971Z","time spent":"774.714818ms","remote":"127.0.0.1:50686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:36:30.948077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.420621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:36:30.949855Z","caller":"traceutil/trace.go:171","msg":"trace[865323376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"188.219581ms","start":"2026-05-19T13:36:30.7616Z","end":"2026-05-19T13:36:30.94982Z","steps":["trace[865323376] 'agreement among raft nodes before linearized reading' (duration: 186.427622ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:32.353987Z","caller":"traceutil/trace.go:171","msg":"trace[1221179249] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"211.206078ms","start":"2026-05-19T13:36:32.142757Z","end":"2026-05-19T13:36:32.353964Z","steps":["trace[1221179249] 'process raft request' (duration: 203.083049ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:43.476799Z","caller":"traceutil/trace.go:171","msg":"trace[488761540] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"119.630543ms","start":"2026-05-19T13:36:43.357145Z","end":"2026-05-19T13:36:43.476775Z","steps":["trace[488761540] 'process raft request' (duration: 119.367046ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:46.169528Z","caller":"traceutil/trace.go:171","msg":"trace[1596906660] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"105.834729ms","start":"2026-05-19T13:36:46.063647Z","end":"2026-05-19T13:36:46.169481Z","steps":["trace[1596906660] 'process raft request' (duration: 105.300348ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:37:21.487313Z","caller":"traceutil/trace.go:171","msg":"trace[1549277592] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"202.440832ms","start":"2026-05-19T13:37:21.284855Z","end":"2026-05-19T13:37:21.487296Z","steps":["trace[1549277592] 'process raft request' (duration: 202.311339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:37:21.817702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.685908ms","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-05-19T13:37:21.817807Z","caller":"traceutil/trace.go:171","msg":"trace[1020479476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"175.837281ms","start":"2026-05-19T13:37:21.641952Z","end":"2026-05-19T13:37:21.817789Z","steps":["trace[1020479476] 'range keys from in-memory index tree' (duration: 175.523444ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:37:22.118121Z","caller":"traceutil/trace.go:171","msg":"trace[798601262] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"297.449196ms","start":"2026-05-19T13:37:21.82065Z","end":"2026-05-19T13:37:22.118099Z","steps":["trace[798601262] 'process raft request' (duration: 297.337674ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:37:22.118146Z","caller":"traceutil/trace.go:171","msg":"trace[740406624] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"352.603442ms","start":"2026-05-19T13:37:21.765513Z","end":"2026-05-19T13:37:22.118116Z","steps":["trace[740406624] 'read index received' (duration: 280.690572ms)","trace[740406624] 'applied index is now lower than readState.Index' (duration: 71.910079ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:37:22.118278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.745324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:37:22.11832Z","caller":"traceutil/trace.go:171","msg":"trace[1851483129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"352.861107ms","start":"2026-05-19T13:37:21.765447Z","end":"2026-05-19T13:37:22.118308Z","steps":["trace[1851483129] 'agreement among raft nodes before linearized reading' (duration: 352.755404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:37:22.118359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:37:21.765421Z","time spent":"352.931839ms","remote":"127.0.0.1:50446","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-05-19T13:37:46.532138Z","caller":"traceutil/trace.go:171","msg":"trace[429233692] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"208.841661ms","start":"2026-05-19T13:37:46.323272Z","end":"2026-05-19T13:37:46.532113Z","steps":["trace[429233692] 'read index received' (duration: 188.63399ms)","trace[429233692] 'applied index is now lower than readState.Index' (duration: 20.205471ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:37:46.532291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.003354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:37:46.532328Z","caller":"traceutil/trace.go:171","msg":"trace[608708143] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1045; }","duration":"209.101756ms","start":"2026-05-19T13:37:46.323216Z","end":"2026-05-19T13:37:46.532318Z","steps":["trace[608708143] 'agreement among raft nodes before linearized reading' (duration: 209.018694ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:37:46.709909Z","caller":"traceutil/trace.go:171","msg":"trace[11349248] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"177.677201ms","start":"2026-05-19T13:37:46.532208Z","end":"2026-05-19T13:37:46.709885Z","steps":["trace[11349248] 'read index received' (duration: 171.734561ms)","trace[11349248] 'applied index is now lower than readState.Index' (duration: 5.94126ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:37:46.710153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.85903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T13:37:46.710241Z","caller":"traceutil/trace.go:171","msg":"trace[112230656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1046; }","duration":"240.994213ms","start":"2026-05-19T13:37:46.469236Z","end":"2026-05-19T13:37:46.710231Z","steps":["trace[112230656] 'agreement among raft nodes before linearized reading' (duration: 240.798229ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:37:46.710213Z","caller":"traceutil/trace.go:171","msg":"trace[1746380800] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"235.34712ms","start":"2026-05-19T13:37:46.47483Z","end":"2026-05-19T13:37:46.710177Z","steps":["trace[1746380800] 'process raft request' (duration: 229.109864ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:37:46.724953Z","caller":"traceutil/trace.go:171","msg":"trace[217320566] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"191.582644ms","start":"2026-05-19T13:37:46.533349Z","end":"2026-05-19T13:37:46.724932Z","steps":["trace[217320566] 'process raft request' (duration: 191.435841ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:37:48.012784Z","caller":"traceutil/trace.go:171","msg":"trace[1728151599] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"269.832574ms","start":"2026-05-19T13:37:47.742928Z","end":"2026-05-19T13:37:48.012761Z","steps":["trace[1728151599] 'read index received' (duration: 269.581559ms)","trace[1728151599] 'applied index is now lower than readState.Index' (duration: 249.685µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:37:48.01296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.012308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:37:48.012998Z","caller":"traceutil/trace.go:171","msg":"trace[381101343] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1052; }","duration":"270.10533ms","start":"2026-05-19T13:37:47.74288Z","end":"2026-05-19T13:37:48.012985Z","steps":["trace[381101343] 'agreement among raft nodes before linearized reading' (duration: 269.984517ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:37:48.013204Z","caller":"traceutil/trace.go:171","msg":"trace[1375054682] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"343.215908ms","start":"2026-05-19T13:37:47.669952Z","end":"2026-05-19T13:37:48.013168Z","steps":["trace[1375054682] 'process raft request' (duration: 342.620115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:37:48.013438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:37:47.669901Z","time spent":"343.381651ms","remote":"127.0.0.1:50710","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-05-19T13:38:10.954141Z","caller":"traceutil/trace.go:171","msg":"trace[623211958] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"194.511458ms","start":"2026-05-19T13:38:10.759611Z","end":"2026-05-19T13:38:10.954123Z","steps":["trace[623211958] 'read index received' (duration: 194.374345ms)","trace[623211958] 'applied index is now lower than readState.Index' (duration: 136.193µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:38:10.954243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.61874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:38:10.954261Z","caller":"traceutil/trace.go:171","msg":"trace[1864738363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1186; }","duration":"194.676712ms","start":"2026-05-19T13:38:10.759577Z","end":"2026-05-19T13:38:10.954254Z","steps":["trace[1864738363] 'agreement among raft nodes before linearized reading' (duration: 194.629771ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:38:10.954284Z","caller":"traceutil/trace.go:171","msg":"trace[248039070] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"590.66394ms","start":"2026-05-19T13:38:10.363574Z","end":"2026-05-19T13:38:10.954237Z","steps":["trace[248039070] 'process raft request' (duration: 590.454256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:38:10.954526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:38:10.363555Z","time spent":"590.855545ms","remote":"127.0.0.1:50710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:38:10.954636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.326784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T13:38:10.954691Z","caller":"traceutil/trace.go:171","msg":"trace[1683967821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1186; }","duration":"123.424896ms","start":"2026-05-19T13:38:10.831254Z","end":"2026-05-19T13:38:10.954679Z","steps":["trace[1683967821] 'agreement among raft nodes before linearized reading' (duration: 123.281703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:38:10.95467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.957523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T13:38:10.954737Z","caller":"traceutil/trace.go:171","msg":"trace[1726852988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1186; }","duration":"117.051796ms","start":"2026-05-19T13:38:10.837669Z","end":"2026-05-19T13:38:10.954721Z","steps":["trace[1726852988] 'agreement among raft nodes before linearized reading' (duration: 116.847551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:38:10.956257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.686077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:38:10.956423Z","caller":"traceutil/trace.go:171","msg":"trace[2062433563] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1186; }","duration":"186.866132ms","start":"2026-05-19T13:38:10.769535Z","end":"2026-05-19T13:38:10.956401Z","steps":["trace[2062433563] 'agreement among raft nodes before linearized reading' (duration: 184.914929ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:38:27.270557Z","caller":"traceutil/trace.go:171","msg":"trace[2141166801] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1305; }","duration":"185.710207ms","start":"2026-05-19T13:38:27.08482Z","end":"2026-05-19T13:38:27.27053Z","steps":["trace[2141166801] 'read index received' (duration: 162.500271ms)","trace[2141166801] 'applied index is now lower than readState.Index' (duration: 23.208796ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:38:27.270786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.941982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-19T13:38:27.270849Z","caller":"traceutil/trace.go:171","msg":"trace[609192921] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1251; }","duration":"186.034593ms","start":"2026-05-19T13:38:27.084802Z","end":"2026-05-19T13:38:27.270836Z","steps":["trace[609192921] 'agreement among raft nodes before linearized reading' (duration: 185.840979ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:38:27.270968Z","caller":"traceutil/trace.go:171","msg":"trace[535385364] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"198.187579ms","start":"2026-05-19T13:38:27.072768Z","end":"2026-05-19T13:38:27.270955Z","steps":["trace[535385364] 'process raft request' (duration: 174.521533ms)","trace[535385364] 'compare' (duration: 23.097484ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:38:27.435298Z","caller":"traceutil/trace.go:171","msg":"trace[422062768] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"176.373113ms","start":"2026-05-19T13:38:27.258874Z","end":"2026-05-19T13:38:27.435247Z","steps":["trace[422062768] 'process raft request' (duration: 147.963424ms)","trace[422062768] 'compare' (duration: 28.205924ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:38:27.435305Z","caller":"traceutil/trace.go:171","msg":"trace[1639111141] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"159.940224ms","start":"2026-05-19T13:38:27.275338Z","end":"2026-05-19T13:38:27.435278Z","steps":["trace[1639111141] 'process raft request' (duration: 159.860403ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:38:27.435344Z","caller":"traceutil/trace.go:171","msg":"trace[287923232] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1306; }","duration":"164.701807ms","start":"2026-05-19T13:38:27.270618Z","end":"2026-05-19T13:38:27.43532Z","steps":["trace[287923232] 'read index received' (duration: 136.209397ms)","trace[287923232] 'applied index is now lower than readState.Index' (duration: 28.48898ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:38:27.435556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.694971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:38:27.435617Z","caller":"traceutil/trace.go:171","msg":"trace[972174739] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1253; }","duration":"149.789463ms","start":"2026-05-19T13:38:27.285812Z","end":"2026-05-19T13:38:27.435601Z","steps":["trace[972174739] 'agreement among raft nodes before linearized reading' (duration: 149.662621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:38:27.435634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.849106ms","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-05-19T13:38:27.435777Z","caller":"traceutil/trace.go:171","msg":"trace[1193091982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1253; }","duration":"308.028811ms","start":"2026-05-19T13:38:27.127719Z","end":"2026-05-19T13:38:27.435748Z","steps":["trace[1193091982] 'agreement among raft nodes before linearized reading' (duration: 307.701433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:38:27.435827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:38:27.127707Z","time spent":"308.110963ms","remote":"127.0.0.1:50710","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-05-19T13:38:27.993969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.922992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:38:27.994095Z","caller":"traceutil/trace.go:171","msg":"trace[31380933] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1312; }","duration":"231.674838ms","start":"2026-05-19T13:38:27.762404Z","end":"2026-05-19T13:38:27.994079Z","steps":["trace[31380933] 'read index received' (duration: 3.431015ms)","trace[31380933] 'applied index is now lower than readState.Index' (duration: 228.242123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:38:27.994215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.81335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:38:27.994194Z","caller":"traceutil/trace.go:171","msg":"trace[340455242] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"259.682908ms","start":"2026-05-19T13:38:27.734442Z","end":"2026-05-19T13:38:27.994125Z","steps":["trace[340455242] 'process raft request' (duration: 134.533391ms)","trace[340455242] 'compare' (duration: 124.82305ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:38:27.994276Z","caller":"traceutil/trace.go:171","msg":"trace[1385960953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1259; }","duration":"231.892072ms","start":"2026-05-19T13:38:27.762347Z","end":"2026-05-19T13:38:27.994239Z","steps":["trace[1385960953] 'agreement among raft nodes before linearized reading' (duration: 231.7748ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:38:28.357256Z","caller":"traceutil/trace.go:171","msg":"trace[1670282594] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"202.87916ms","start":"2026-05-19T13:38:28.154348Z","end":"2026-05-19T13:38:28.357227Z","steps":["trace[1670282594] 'process raft request' (duration: 199.441776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:38:31.50119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.902504ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-19T13:38:31.501481Z","caller":"traceutil/trace.go:171","msg":"trace[1671039822] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"154.66649ms","start":"2026-05-19T13:38:31.346779Z","end":"2026-05-19T13:38:31.501446Z","steps":["trace[1671039822] 'process raft request' (duration: 154.522907ms)"],"step_count":1}