{"level":"warn","ts":"2026-05-08T18:21:57.853054Z","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-08T18:21:57.853148Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.206: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.206:2380","--initial-cluster=instance=https://199.19.213.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.206: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-08T18:21:57.853218Z","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-08T18:21:57.853235Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.206:2380"]} {"level":"info","ts":"2026-05-08T18:21:57.853258Z","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-08T18:21:57.854355Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.206:2379"]} {"level":"info","ts":"2026-05-08T18:21:57.854448Z","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.206:2380"],"listen-peer-urls":["https://199.19.213.206:2380"],"advertise-client-urls":["https://199.19.213.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.206: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-08T18:21:57.859371Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.650204ms"} {"level":"info","ts":"2026-05-08T18:21:57.883185Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b696d7a595057bfe","cluster-id":"9b00ba6f3f3b7ac2"} {"level":"info","ts":"2026-05-08T18:21:57.883276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T18:21:57.883334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became follower at term 0"} {"level":"info","ts":"2026-05-08T18:21:57.883348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b696d7a595057bfe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T18:21:57.883359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became follower at term 1"} {"level":"info","ts":"2026-05-08T18:21:57.883399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe switched to configuration voters=(13156940467579223038)"} {"level":"warn","ts":"2026-05-08T18:21:57.894357Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T18:21:57.896304Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T18:21:57.897958Z","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-08T18:21:57.900764Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b696d7a595057bfe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T18:21:57.90214Z","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-08T18:21:57.902175Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b696d7a595057bfe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T18:21:57.902299Z","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-08T18:21:57.902328Z","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-08T18:21:57.904324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe switched to configuration voters=(13156940467579223038)"} {"level":"info","ts":"2026-05-08T18:21:57.904472Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b00ba6f3f3b7ac2","local-member-id":"b696d7a595057bfe","added-peer-id":"b696d7a595057bfe","added-peer-peer-urls":["https://199.19.213.206:2380"]} {"level":"info","ts":"2026-05-08T18:21:57.905334Z","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-08T18:21:57.905527Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b696d7a595057bfe","initial-advertise-peer-urls":["https://199.19.213.206:2380"],"listen-peer-urls":["https://199.19.213.206:2380"],"advertise-client-urls":["https://199.19.213.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T18:21:57.905614Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T18:21:57.90553Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.206:2380"} {"level":"info","ts":"2026-05-08T18:21:57.905846Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.206:2380"} {"level":"info","ts":"2026-05-08T18:21:58.284376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T18:21:58.284461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T18:21:58.284766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe received MsgPreVoteResp from b696d7a595057bfe at term 1"} {"level":"info","ts":"2026-05-08T18:21:58.284811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became candidate at term 2"} {"level":"info","ts":"2026-05-08T18:21:58.284997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe received MsgVoteResp from b696d7a595057bfe at term 2"} {"level":"info","ts":"2026-05-08T18:21:58.285027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became leader at term 2"} {"level":"info","ts":"2026-05-08T18:21:58.285113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b696d7a595057bfe elected leader b696d7a595057bfe at term 2"} {"level":"info","ts":"2026-05-08T18:21:58.312607Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:21:58.328735Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b696d7a595057bfe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.206:2379]}","request-path":"/0/members/b696d7a595057bfe/attributes","cluster-id":"9b00ba6f3f3b7ac2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T18:21:58.328841Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T18:21:58.328991Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T18:21:58.330263Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b00ba6f3f3b7ac2","local-member-id":"b696d7a595057bfe","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:21:58.33114Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:21:58.331197Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:21:58.331954Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T18:21:58.341382Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.206:2379"} {"level":"info","ts":"2026-05-08T18:21:58.345789Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T18:21:58.345814Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T18:22:17.740507Z","caller":"traceutil/trace.go:171","msg":"trace[630317145] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"240.316115ms","start":"2026-05-08T18:22:17.500165Z","end":"2026-05-08T18:22:17.740481Z","steps":["trace[630317145] 'process raft request' (duration: 240.105151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:18.077519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.089679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:22:18.077637Z","caller":"traceutil/trace.go:171","msg":"trace[1999293772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"240.255723ms","start":"2026-05-08T18:22:17.837355Z","end":"2026-05-08T18:22:18.077611Z","steps":["trace[1999293772] 'range keys from in-memory index tree' (duration: 239.997468ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:28.189513Z","caller":"traceutil/trace.go:171","msg":"trace[539989111] transaction","detail":"{read_only:false; number_of_response:1; response_revision:481; }","duration":"152.389812ms","start":"2026-05-08T18:22:28.037094Z","end":"2026-05-08T18:22:28.189483Z","steps":["trace[539989111] 'process raft request' (duration: 152.204698ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:28.189531Z","caller":"traceutil/trace.go:171","msg":"trace[496382356] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"153.607049ms","start":"2026-05-08T18:22:28.035855Z","end":"2026-05-08T18:22:28.189462Z","steps":["trace[496382356] 'process raft request' (duration: 153.298402ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:28.189767Z","caller":"traceutil/trace.go:171","msg":"trace[1561785491] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"152.065486ms","start":"2026-05-08T18:22:28.037695Z","end":"2026-05-08T18:22:28.189761Z","steps":["trace[1561785491] 'process raft request' (duration: 151.728249ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:28.189641Z","caller":"traceutil/trace.go:171","msg":"trace[769042455] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"153.537567ms","start":"2026-05-08T18:22:28.036083Z","end":"2026-05-08T18:22:28.189621Z","steps":["trace[769042455] 'process raft request' (duration: 153.18118ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:28.199747Z","caller":"traceutil/trace.go:171","msg":"trace[214144631] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"114.982189ms","start":"2026-05-08T18:22:28.084734Z","end":"2026-05-08T18:22:28.199716Z","steps":["trace[214144631] 'process raft request' (duration: 114.685773ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:28.199843Z","caller":"traceutil/trace.go:171","msg":"trace[95655747] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"114.200733ms","start":"2026-05-08T18:22:28.085626Z","end":"2026-05-08T18:22:28.199827Z","steps":["trace[95655747] 'process raft request' (duration: 113.823765ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:28.199956Z","caller":"traceutil/trace.go:171","msg":"trace[268933639] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"105.657984ms","start":"2026-05-08T18:22:28.094292Z","end":"2026-05-08T18:22:28.19995Z","steps":["trace[268933639] 'process raft request' (duration: 105.189655ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:28.199976Z","caller":"traceutil/trace.go:171","msg":"trace[2092698846] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"115.759916ms","start":"2026-05-08T18:22:28.084209Z","end":"2026-05-08T18:22:28.199969Z","steps":["trace[2092698846] 'process raft request' (duration: 114.960668ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:28.200022Z","caller":"traceutil/trace.go:171","msg":"trace[907116189] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"104.46745ms","start":"2026-05-08T18:22:28.095549Z","end":"2026-05-08T18:22:28.200016Z","steps":["trace[907116189] 'process raft request' (duration: 103.958449ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:28.200043Z","caller":"traceutil/trace.go:171","msg":"trace[190325669] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"115.651782ms","start":"2026-05-08T18:22:28.084386Z","end":"2026-05-08T18:22:28.200038Z","steps":["trace[190325669] 'process raft request' (duration: 114.851436ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:28.200105Z","caller":"traceutil/trace.go:171","msg":"trace[686774855] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"103.770785ms","start":"2026-05-08T18:22:28.096328Z","end":"2026-05-08T18:22:28.200098Z","steps":["trace[686774855] 'process raft request' (duration: 103.214393ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:28.20025Z","caller":"traceutil/trace.go:171","msg":"trace[1247882765] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"115.673563ms","start":"2026-05-08T18:22:28.084564Z","end":"2026-05-08T18:22:28.200238Z","steps":["trace[1247882765] 'process raft request' (duration: 114.700133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:34.744336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.631693ms","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-08T18:22:34.744459Z","caller":"traceutil/trace.go:171","msg":"trace[898307504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"462.800306ms","start":"2026-05-08T18:22:34.281633Z","end":"2026-05-08T18:22:34.744433Z","steps":["trace[898307504] 'range keys from in-memory index tree' (duration: 462.386717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:34.744526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:34.281591Z","time spent":"462.913088ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T18:22:34.744514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.229554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fwld4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-08T18:22:34.74473Z","caller":"traceutil/trace.go:171","msg":"trace[1601794789] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fwld4; range_end:; response_count:1; response_revision:578; }","duration":"547.48284ms","start":"2026-05-08T18:22:34.197222Z","end":"2026-05-08T18:22:34.744705Z","steps":["trace[1601794789] 'range keys from in-memory index tree' (duration: 547.122902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:34.74478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:34.197207Z","time spent":"547.559861ms","remote":"127.0.0.1:47096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-fwld4\" "} {"level":"warn","ts":"2026-05-08T18:22:34.744465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.081655ms","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-05-08T18:22:34.745249Z","caller":"traceutil/trace.go:171","msg":"trace[2120733853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"446.676858ms","start":"2026-05-08T18:22:34.298311Z","end":"2026-05-08T18:22:34.744988Z","steps":["trace[2120733853] 'range keys from in-memory index tree' (duration: 445.907232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:34.745324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:34.298295Z","time spent":"447.014115ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T18:22:34.907253Z","caller":"traceutil/trace.go:171","msg":"trace[1341079944] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"497.890041ms","start":"2026-05-08T18:22:34.409338Z","end":"2026-05-08T18:22:34.907228Z","steps":["trace[1341079944] 'process raft request' (duration: 497.3644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:34.907876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:34.409316Z","time spent":"498.005184ms","remote":"127.0.0.1:47008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:22:35.140789Z","caller":"traceutil/trace.go:171","msg":"trace[1700909837] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"383.177498ms","start":"2026-05-08T18:22:34.757588Z","end":"2026-05-08T18:22:35.140765Z","steps":["trace[1700909837] 'process raft request' (duration: 383.104856ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:35.1409Z","caller":"traceutil/trace.go:171","msg":"trace[394986916] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"390.367978ms","start":"2026-05-08T18:22:34.750487Z","end":"2026-05-08T18:22:35.140855Z","steps":["trace[394986916] 'process raft request' (duration: 390.104143ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:35.141163Z","caller":"traceutil/trace.go:171","msg":"trace[952136566] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"391.423951ms","start":"2026-05-08T18:22:34.7497Z","end":"2026-05-08T18:22:35.141123Z","steps":["trace[952136566] 'process raft request' (duration: 390.770927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:35.141218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:34.750479Z","time spent":"390.642565ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:22:35.141311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:34.749681Z","time spent":"391.560713ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:22:35.14093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:34.757569Z","time spent":"383.29616ms","remote":"127.0.0.1:47096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:22:35.163521Z","caller":"traceutil/trace.go:171","msg":"trace[935049804] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:605; }","duration":"130.806371ms","start":"2026-05-08T18:22:35.03269Z","end":"2026-05-08T18:22:35.163497Z","steps":["trace[935049804] 'read index received' (duration: 107.768597ms)","trace[935049804] 'applied index is now lower than readState.Index' (duration: 23.036864ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:22:35.163735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.999174ms","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-08T18:22:35.163797Z","caller":"traceutil/trace.go:171","msg":"trace[1042551431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"131.132458ms","start":"2026-05-08T18:22:35.032648Z","end":"2026-05-08T18:22:35.16378Z","steps":["trace[1042551431] 'agreement among raft nodes before linearized reading' (duration: 130.943844ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:35.16382Z","caller":"traceutil/trace.go:171","msg":"trace[1315034561] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"199.164733ms","start":"2026-05-08T18:22:34.964457Z","end":"2026-05-08T18:22:35.163622Z","steps":["trace[1315034561] 'process raft request' (duration: 198.880256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:35.848292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.390835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:22:35.848392Z","caller":"traceutil/trace.go:171","msg":"trace[310263603] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"660.154671ms","start":"2026-05-08T18:22:35.188215Z","end":"2026-05-08T18:22:35.848369Z","steps":["trace[310263603] 'process raft request' (duration: 389.50344ms)","trace[310263603] 'compare' (duration: 270.057709ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:22:35.848472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:35.188192Z","time spent":"660.243323ms","remote":"127.0.0.1:47096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T18:22:37.515601Z","caller":"traceutil/trace.go:171","msg":"trace[78299212] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"141.30427ms","start":"2026-05-08T18:22:37.374276Z","end":"2026-05-08T18:22:37.51558Z","steps":["trace[78299212] 'read index received' (duration: 109.650687ms)","trace[78299212] 'applied index is now lower than readState.Index' (duration: 31.652553ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:22:37.515779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.473724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:22:37.515731Z","caller":"traceutil/trace.go:171","msg":"trace[1246338307] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"141.529855ms","start":"2026-05-08T18:22:37.374176Z","end":"2026-05-08T18:22:37.515706Z","steps":["trace[1246338307] 'process raft request' (duration: 109.741259ms)","trace[1246338307] 'compare' (duration: 31.212854ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:22:37.515824Z","caller":"traceutil/trace.go:171","msg":"trace[204599233] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:607; }","duration":"141.537715ms","start":"2026-05-08T18:22:37.374274Z","end":"2026-05-08T18:22:37.515812Z","steps":["trace[204599233] 'agreement among raft nodes before linearized reading' (duration: 141.421673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:37.51972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.696352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:22:37.519775Z","caller":"traceutil/trace.go:171","msg":"trace[890605010] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:608; }","duration":"144.751413ms","start":"2026-05-08T18:22:37.375009Z","end":"2026-05-08T18:22:37.51976Z","steps":["trace[890605010] 'agreement among raft nodes before linearized reading' (duration: 144.663311ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:37.519917Z","caller":"traceutil/trace.go:171","msg":"trace[2061829935] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"144.267923ms","start":"2026-05-08T18:22:37.375631Z","end":"2026-05-08T18:22:37.519899Z","steps":["trace[2061829935] 'process raft request' (duration: 143.970587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:37.520754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.19295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:22:37.520923Z","caller":"traceutil/trace.go:171","msg":"trace[505853141] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:608; }","duration":"144.390625ms","start":"2026-05-08T18:22:37.376518Z","end":"2026-05-08T18:22:37.520908Z","steps":["trace[505853141] 'agreement among raft nodes before linearized reading' (duration: 144.15964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:37.520915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.457087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:22:37.521059Z","caller":"traceutil/trace.go:171","msg":"trace[231665064] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:608; }","duration":"144.641761ms","start":"2026-05-08T18:22:37.376395Z","end":"2026-05-08T18:22:37.521037Z","steps":["trace[231665064] 'agreement among raft nodes before linearized reading' (duration: 144.451937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:37.521112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.405895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:22:37.521162Z","caller":"traceutil/trace.go:171","msg":"trace[948782491] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:608; }","duration":"144.460876ms","start":"2026-05-08T18:22:37.376689Z","end":"2026-05-08T18:22:37.52115Z","steps":["trace[948782491] 'agreement among raft nodes before linearized reading' (duration: 144.392014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:37.521505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.277614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:22:37.521551Z","caller":"traceutil/trace.go:171","msg":"trace[199981250] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:608; }","duration":"146.332516ms","start":"2026-05-08T18:22:37.375206Z","end":"2026-05-08T18:22:37.521538Z","steps":["trace[199981250] 'agreement among raft nodes before linearized reading' (duration: 146.271015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:37.522773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.04572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:22:37.522823Z","caller":"traceutil/trace.go:171","msg":"trace[1045557100] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:608; }","duration":"147.585112ms","start":"2026-05-08T18:22:37.375225Z","end":"2026-05-08T18:22:37.52281Z","steps":["trace[1045557100] 'agreement among raft nodes before linearized reading' (duration: 146.031049ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:42.445687Z","caller":"traceutil/trace.go:171","msg":"trace[1643589265] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"104.007029ms","start":"2026-05-08T18:22:42.341656Z","end":"2026-05-08T18:22:42.445663Z","steps":["trace[1643589265] 'process raft request' (duration: 100.999736ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:42.831218Z","caller":"traceutil/trace.go:171","msg":"trace[715729990] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"102.893406ms","start":"2026-05-08T18:22:42.728299Z","end":"2026-05-08T18:22:42.831193Z","steps":["trace[715729990] 'process raft request' (duration: 102.699222ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:50.225598Z","caller":"traceutil/trace.go:171","msg":"trace[1348777829] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"288.577065ms","start":"2026-05-08T18:22:49.936997Z","end":"2026-05-08T18:22:50.225574Z","steps":["trace[1348777829] 'process raft request' (duration: 288.424492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:50.516579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.281973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pwzkk\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-08T18:22:50.516655Z","caller":"traceutil/trace.go:171","msg":"trace[1255758165] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pwzkk; range_end:; response_count:1; response_revision:740; }","duration":"218.400666ms","start":"2026-05-08T18:22:50.298237Z","end":"2026-05-08T18:22:50.516638Z","steps":["trace[1255758165] 'range keys from in-memory index tree' (duration: 218.068909ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:23:25.380778Z","caller":"traceutil/trace.go:171","msg":"trace[1349863862] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"103.728973ms","start":"2026-05-08T18:23:25.277024Z","end":"2026-05-08T18:23:25.380753Z","steps":["trace[1349863862] 'process raft request' (duration: 103.59372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:23:25.886166Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934752471489512208,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T18:23:26.296707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.572722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:23:26.296881Z","caller":"traceutil/trace.go:171","msg":"trace[1125570111] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"911.410738ms","start":"2026-05-08T18:23:25.385442Z","end":"2026-05-08T18:23:26.296853Z","steps":["trace[1125570111] 'read index received' (duration: 563.52786ms)","trace[1125570111] 'applied index is now lower than readState.Index' (duration: 347.880898ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:23:26.297073Z","caller":"traceutil/trace.go:171","msg":"trace[1295879619] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"911.738355ms","start":"2026-05-08T18:23:25.385294Z","end":"2026-05-08T18:23:26.297032Z","steps":["trace[1295879619] 'process raft request' (duration: 563.662643ms)","trace[1295879619] 'compare' (duration: 347.409417ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:23:26.297195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:23:25.38527Z","time spent":"911.870509ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:23:26.297246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.775196ms","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-05-08T18:23:26.297322Z","caller":"traceutil/trace.go:171","msg":"trace[518809490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"911.888698ms","start":"2026-05-08T18:23:25.385417Z","end":"2026-05-08T18:23:26.297306Z","steps":["trace[518809490] 'agreement among raft nodes before linearized reading' (duration: 911.674294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:23:26.297369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:23:25.385409Z","time spent":"911.95503ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T18:23:26.297982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.944009ms","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-05-08T18:23:26.298019Z","caller":"traceutil/trace.go:171","msg":"trace[1961225001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"590.057451ms","start":"2026-05-08T18:23:25.707953Z","end":"2026-05-08T18:23:26.29801Z","steps":["trace[1961225001] 'agreement among raft nodes before linearized reading' (duration: 589.950259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:23:26.298037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:23:25.707934Z","time spent":"590.098972ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T18:23:26.298109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.176832ms","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-05-08T18:23:26.298168Z","caller":"traceutil/trace.go:171","msg":"trace[480466369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:960; }","duration":"871.268134ms","start":"2026-05-08T18:23:25.426883Z","end":"2026-05-08T18:23:26.298151Z","steps":["trace[480466369] 'agreement among raft nodes before linearized reading' (duration: 870.946677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:23:26.298213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:23:25.426869Z","time spent":"871.332865ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T18:23:49.239786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.166598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:23:49.240005Z","caller":"traceutil/trace.go:171","msg":"trace[262366069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"403.419183ms","start":"2026-05-08T18:23:48.836568Z","end":"2026-05-08T18:23:49.239988Z","steps":["trace[262366069] 'range keys from in-memory index tree' (duration: 403.059595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:23:49.240046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:23:48.836554Z","time spent":"403.480136ms","remote":"127.0.0.1:46918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T18:23:51.734911Z","caller":"traceutil/trace.go:171","msg":"trace[1856175462] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"107.471524ms","start":"2026-05-08T18:23:51.627421Z","end":"2026-05-08T18:23:51.734893Z","steps":["trace[1856175462] 'process raft request' (duration: 107.369571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:04.338326Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934752471489512629,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T18:24:04.6207Z","caller":"traceutil/trace.go:171","msg":"trace[20851853] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"783.133637ms","start":"2026-05-08T18:24:03.837544Z","end":"2026-05-08T18:24:04.620678Z","steps":["trace[20851853] 'read index received' (duration: 782.944783ms)","trace[20851853] 'applied index is now lower than readState.Index' (duration: 188.034µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:24:04.621078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.533126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:24:04.621116Z","caller":"traceutil/trace.go:171","msg":"trace[1234408249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1136; }","duration":"783.613977ms","start":"2026-05-08T18:24:03.83749Z","end":"2026-05-08T18:24:04.621104Z","steps":["trace[1234408249] 'agreement among raft nodes before linearized reading' (duration: 783.525605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:04.621146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:24:03.837471Z","time spent":"783.668339ms","remote":"127.0.0.1:46932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T18:24:04.621337Z","caller":"traceutil/trace.go:171","msg":"trace[909073119] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"853.031321ms","start":"2026-05-08T18:24:03.768295Z","end":"2026-05-08T18:24:04.621326Z","steps":["trace[909073119] 'process raft request' (duration: 852.261034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:04.621413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:24:03.768279Z","time spent":"853.080651ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:24:05.159542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934752471489512632,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T18:24:05.175242Z","caller":"traceutil/trace.go:171","msg":"trace[1015911112] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1185; }","duration":"516.746823ms","start":"2026-05-08T18:24:04.658477Z","end":"2026-05-08T18:24:05.175224Z","steps":["trace[1015911112] 'read index received' (duration: 515.471885ms)","trace[1015911112] 'applied index is now lower than readState.Index' (duration: 1.274148ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:24:05.175464Z","caller":"traceutil/trace.go:171","msg":"trace[1897047449] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"549.655161ms","start":"2026-05-08T18:24:04.625799Z","end":"2026-05-08T18:24:05.175454Z","steps":["trace[1897047449] 'process raft request' (duration: 548.134698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:05.175542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:24:04.62578Z","time spent":"549.710212ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:24:05.175659Z","caller":"traceutil/trace.go:171","msg":"trace[1994247297] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"549.433346ms","start":"2026-05-08T18:24:04.62622Z","end":"2026-05-08T18:24:05.175653Z","steps":["trace[1994247297] 'process raft request' (duration: 548.966657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:05.175714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:24:04.626195Z","time spent":"549.497837ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:24:05.175847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.376636ms","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-05-08T18:24:05.175872Z","caller":"traceutil/trace.go:171","msg":"trace[1750389118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1138; }","duration":"517.437078ms","start":"2026-05-08T18:24:04.658429Z","end":"2026-05-08T18:24:05.175866Z","steps":["trace[1750389118] 'agreement among raft nodes before linearized reading' (duration: 517.357436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:05.17589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:24:04.658409Z","time spent":"517.477668ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T18:24:05.175997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.54117ms","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-05-08T18:24:05.176018Z","caller":"traceutil/trace.go:171","msg":"trace[1160230890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1138; }","duration":"491.56219ms","start":"2026-05-08T18:24:04.684451Z","end":"2026-05-08T18:24:05.176013Z","steps":["trace[1160230890] 'agreement among raft nodes before linearized reading' (duration: 491.511349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:05.176034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:24:04.6844Z","time spent":"491.631431ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T18:24:05.1762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.472129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:24:05.176224Z","caller":"traceutil/trace.go:171","msg":"trace[249707005] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1138; }","duration":"150.52651ms","start":"2026-05-08T18:24:05.025691Z","end":"2026-05-08T18:24:05.176218Z","steps":["trace[249707005] 'agreement among raft nodes before linearized reading' (duration: 150.474039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:05.17634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.712611ms","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-08T18:24:05.176363Z","caller":"traceutil/trace.go:171","msg":"trace[799765985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1138; }","duration":"338.747211ms","start":"2026-05-08T18:24:04.837609Z","end":"2026-05-08T18:24:05.176356Z","steps":["trace[799765985] 'agreement among raft nodes before linearized reading' (duration: 338.69254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:05.176381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:24:04.837601Z","time spent":"338.776892ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T18:24:05.176457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.164971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:24:05.176476Z","caller":"traceutil/trace.go:171","msg":"trace[970745776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1138; }","duration":"340.210743ms","start":"2026-05-08T18:24:04.83626Z","end":"2026-05-08T18:24:05.176471Z","steps":["trace[970745776] 'agreement among raft nodes before linearized reading' (duration: 340.183792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:05.176493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:24:04.836243Z","time spent":"340.246713ms","remote":"127.0.0.1:46918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T18:24:21.406769Z","caller":"traceutil/trace.go:171","msg":"trace[1435481285] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1261; }","duration":"151.465552ms","start":"2026-05-08T18:24:21.255286Z","end":"2026-05-08T18:24:21.406751Z","steps":["trace[1435481285] 'read index received' (duration: 151.460542ms)","trace[1435481285] 'applied index is now lower than readState.Index' (duration: 3.75µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:24:21.406971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.695239ms","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-05-08T18:24:21.407005Z","caller":"traceutil/trace.go:171","msg":"trace[213283849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1208; }","duration":"142.75791ms","start":"2026-05-08T18:24:21.264235Z","end":"2026-05-08T18:24:21.406993Z","steps":["trace[213283849] 'agreement among raft nodes before linearized reading' (duration: 142.622787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:21.407317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.019864ms","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-08T18:24:21.407364Z","caller":"traceutil/trace.go:171","msg":"trace[225853172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1208; }","duration":"152.097765ms","start":"2026-05-08T18:24:21.255252Z","end":"2026-05-08T18:24:21.40735Z","steps":["trace[225853172] 'agreement among raft nodes before linearized reading' (duration: 151.971302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:21.407638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.276592ms","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-05-08T18:24:21.407682Z","caller":"traceutil/trace.go:171","msg":"trace[2081483519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1208; }","duration":"112.343804ms","start":"2026-05-08T18:24:21.295326Z","end":"2026-05-08T18:24:21.407669Z","steps":["trace[2081483519] 'agreement among raft nodes before linearized reading' (duration: 112.227391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:21.407873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.741674ms","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-08T18:24:21.407926Z","caller":"traceutil/trace.go:171","msg":"trace[214798805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1208; }","duration":"123.815495ms","start":"2026-05-08T18:24:21.284095Z","end":"2026-05-08T18:24:21.407911Z","steps":["trace[214798805] 'agreement among raft nodes before linearized reading' (duration: 123.651582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:21.408271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.371535ms","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-08T18:24:21.408323Z","caller":"traceutil/trace.go:171","msg":"trace[744715442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1208; }","duration":"143.434797ms","start":"2026-05-08T18:24:21.264876Z","end":"2026-05-08T18:24:21.40831Z","steps":["trace[744715442] 'agreement among raft nodes before linearized reading' (duration: 143.306634ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:23.61787Z","caller":"traceutil/trace.go:171","msg":"trace[919372133] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"138.17002ms","start":"2026-05-08T18:24:23.479677Z","end":"2026-05-08T18:24:23.617847Z","steps":["trace[919372133] 'process raft request' (duration: 137.985456ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:23.618123Z","caller":"traceutil/trace.go:171","msg":"trace[2065157048] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"138.361724ms","start":"2026-05-08T18:24:23.479743Z","end":"2026-05-08T18:24:23.618105Z","steps":["trace[2065157048] 'process raft request' (duration: 138.060907ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:23.618206Z","caller":"traceutil/trace.go:171","msg":"trace[125396549] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"138.320572ms","start":"2026-05-08T18:24:23.479875Z","end":"2026-05-08T18:24:23.618196Z","steps":["trace[125396549] 'process raft request' (duration: 138.171899ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:23.618387Z","caller":"traceutil/trace.go:171","msg":"trace[871050148] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"138.19213ms","start":"2026-05-08T18:24:23.480179Z","end":"2026-05-08T18:24:23.618371Z","steps":["trace[871050148] 'process raft request' (duration: 137.974036ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:34.935039Z","caller":"traceutil/trace.go:171","msg":"trace[1984324947] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"126.036924ms","start":"2026-05-08T18:24:34.808981Z","end":"2026-05-08T18:24:34.935018Z","steps":["trace[1984324947] 'read index received' (duration: 125.812449ms)","trace[1984324947] 'applied index is now lower than readState.Index' (duration: 223.645µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:24:34.935103Z","caller":"traceutil/trace.go:171","msg":"trace[1041565562] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"151.954582ms","start":"2026-05-08T18:24:34.783126Z","end":"2026-05-08T18:24:34.93508Z","steps":["trace[1041565562] 'process raft request' (duration: 151.756318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:34.935256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.255598ms","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-05-08T18:24:34.935292Z","caller":"traceutil/trace.go:171","msg":"trace[1613599660] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1303; }","duration":"126.33356ms","start":"2026-05-08T18:24:34.808946Z","end":"2026-05-08T18:24:34.93528Z","steps":["trace[1613599660] 'agreement among raft nodes before linearized reading' (duration: 126.185407ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:35.493258Z","caller":"traceutil/trace.go:171","msg":"trace[1868796751] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"553.083678ms","start":"2026-05-08T18:24:34.940139Z","end":"2026-05-08T18:24:35.493223Z","steps":["trace[1868796751] 'process raft request' (duration: 552.888304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:35.493469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:24:34.940115Z","time spent":"553.223891ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:24:35.716053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.183555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:24:35.716349Z","caller":"traceutil/trace.go:171","msg":"trace[1718808404] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"158.945415ms","start":"2026-05-08T18:24:35.557385Z","end":"2026-05-08T18:24:35.71633Z","steps":["trace[1718808404] 'read index received' (duration: 50.511µs)","trace[1718808404] 'applied index is now lower than readState.Index' (duration: 158.892944ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:24:35.716432Z","caller":"traceutil/trace.go:171","msg":"trace[749226840] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"218.382119ms","start":"2026-05-08T18:24:35.498017Z","end":"2026-05-08T18:24:35.716399Z","steps":["trace[749226840] 'process raft request' (duration: 56.772005ms)","trace[749226840] 'compare' (duration: 161.037341ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:24:35.716671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.269263ms","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-08T18:24:35.716741Z","caller":"traceutil/trace.go:171","msg":"trace[309302157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1305; }","duration":"159.392136ms","start":"2026-05-08T18:24:35.55733Z","end":"2026-05-08T18:24:35.716722Z","steps":["trace[309302157] 'agreement among raft nodes before linearized reading' (duration: 159.186211ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:05.739099Z","caller":"traceutil/trace.go:171","msg":"trace[296065216] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"183.438562ms","start":"2026-05-08T18:25:05.555644Z","end":"2026-05-08T18:25:05.739083Z","steps":["trace[296065216] 'process raft request' (duration: 183.33445ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:55.400551Z","caller":"traceutil/trace.go:171","msg":"trace[409578169] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"119.452993ms","start":"2026-05-08T18:25:55.281059Z","end":"2026-05-08T18:25:55.400512Z","steps":["trace[409578169] 'process raft request' (duration: 119.287149ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:56.550247Z","caller":"traceutil/trace.go:171","msg":"trace[649864034] transaction","detail":"{read_only:false; response_revision:1678; number_of_response:1; }","duration":"109.150434ms","start":"2026-05-08T18:25:56.441Z","end":"2026-05-08T18:25:56.550151Z","steps":["trace[649864034] 'process raft request' (duration: 92.199937ms)","trace[649864034] 'compare' (duration: 16.684211ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:27:01.045839Z","caller":"traceutil/trace.go:171","msg":"trace[211460140] linearizableReadLoop","detail":"{readStateIndex:2056; appliedIndex:2055; }","duration":"113.821767ms","start":"2026-05-08T18:27:00.931997Z","end":"2026-05-08T18:27:01.045819Z","steps":["trace[211460140] 'read index received' (duration: 65.803713ms)","trace[211460140] 'applied index is now lower than readState.Index' (duration: 48.016934ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:27:01.045861Z","caller":"traceutil/trace.go:171","msg":"trace[1260832169] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"185.148713ms","start":"2026-05-08T18:27:00.860683Z","end":"2026-05-08T18:27:01.045832Z","steps":["trace[1260832169] 'process raft request' (duration: 137.116538ms)","trace[1260832169] 'compare' (duration: 47.912762ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:27:01.046078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.104833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-08T18:27:01.046122Z","caller":"traceutil/trace.go:171","msg":"trace[1034172587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1972; }","duration":"114.194696ms","start":"2026-05-08T18:27:00.931916Z","end":"2026-05-08T18:27:01.046111Z","steps":["trace[1034172587] 'agreement among raft nodes before linearized reading' (duration: 114.064433ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:27:01.443521Z","caller":"traceutil/trace.go:171","msg":"trace[467145844] linearizableReadLoop","detail":"{readStateIndex:2061; appliedIndex:2060; }","duration":"122.89948ms","start":"2026-05-08T18:27:01.320596Z","end":"2026-05-08T18:27:01.443496Z","steps":["trace[467145844] 'read index received' (duration: 121.824127ms)","trace[467145844] 'applied index is now lower than readState.Index' (duration: 1.074283ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:27:01.44358Z","caller":"traceutil/trace.go:171","msg":"trace[1569485596] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"183.898896ms","start":"2026-05-08T18:27:01.259656Z","end":"2026-05-08T18:27:01.443555Z","steps":["trace[1569485596] 'process raft request' (duration: 182.769211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:27:01.443769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.102725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T18:27:01.443828Z","caller":"traceutil/trace.go:171","msg":"trace[566015241] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1976; }","duration":"123.264238ms","start":"2026-05-08T18:27:01.320546Z","end":"2026-05-08T18:27:01.44381Z","steps":["trace[566015241] 'agreement among raft nodes before linearized reading' (duration: 123.060604ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:27:41.50505Z","caller":"traceutil/trace.go:171","msg":"trace[1743528177] linearizableReadLoop","detail":"{readStateIndex:2275; appliedIndex:2273; }","duration":"129.373766ms","start":"2026-05-08T18:27:41.375651Z","end":"2026-05-08T18:27:41.505024Z","steps":["trace[1743528177] 'read index received' (duration: 76.446177ms)","trace[1743528177] 'applied index is now lower than readState.Index' (duration: 52.925959ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:27:41.505094Z","caller":"traceutil/trace.go:171","msg":"trace[2024097826] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"139.047044ms","start":"2026-05-08T18:27:41.366006Z","end":"2026-05-08T18:27:41.505053Z","steps":["trace[2024097826] 'process raft request' (duration: 118.963273ms)","trace[2024097826] 'compare' (duration: 19.935767ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:27:41.505294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.625963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-08T18:27:41.505335Z","caller":"traceutil/trace.go:171","msg":"trace[346649356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2181; }","duration":"129.701585ms","start":"2026-05-08T18:27:41.375622Z","end":"2026-05-08T18:27:41.505324Z","steps":["trace[346649356] 'agreement among raft nodes before linearized reading' (duration: 129.51199ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:27:45.53634Z","caller":"traceutil/trace.go:171","msg":"trace[922140534] linearizableReadLoop","detail":"{readStateIndex:2292; appliedIndex:2291; }","duration":"343.645811ms","start":"2026-05-08T18:27:45.192667Z","end":"2026-05-08T18:27:45.536313Z","steps":["trace[922140534] 'read index received' (duration: 343.489147ms)","trace[922140534] 'applied index is now lower than readState.Index' (duration: 155.164µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:27:45.53652Z","caller":"traceutil/trace.go:171","msg":"trace[1315193356] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"344.913038ms","start":"2026-05-08T18:27:45.191563Z","end":"2026-05-08T18:27:45.536476Z","steps":["trace[1315193356] 'process raft request' (duration: 344.605612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:27:45.536682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.994638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T18:27:45.536725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:27:45.191532Z","time spent":"345.094213ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:27:45.536761Z","caller":"traceutil/trace.go:171","msg":"trace[174475057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2198; }","duration":"344.130711ms","start":"2026-05-08T18:27:45.192611Z","end":"2026-05-08T18:27:45.536741Z","steps":["trace[174475057] 'agreement among raft nodes before linearized reading' (duration: 343.892496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:27:45.536809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:27:45.192586Z","time spent":"344.209023ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T18:27:56.136212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934752471489515314,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T18:27:56.186035Z","caller":"traceutil/trace.go:171","msg":"trace[1674506411] linearizableReadLoop","detail":"{readStateIndex:2348; appliedIndex:2346; }","duration":"550.226281ms","start":"2026-05-08T18:27:55.635782Z","end":"2026-05-08T18:27:56.186008Z","steps":["trace[1674506411] 'read index received' (duration: 528.867511ms)","trace[1674506411] 'applied index is now lower than readState.Index' (duration: 21.35786ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:27:56.186037Z","caller":"traceutil/trace.go:171","msg":"trace[638272240] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"561.892292ms","start":"2026-05-08T18:27:55.624101Z","end":"2026-05-08T18:27:56.185994Z","steps":["trace[638272240] 'process raft request' (duration: 540.629895ms)","trace[638272240] 'compare' (duration: 19.896157ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:27:56.186245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.225925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T18:27:56.186295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.579593ms","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-05-08T18:27:56.186379Z","caller":"traceutil/trace.go:171","msg":"trace[1688900536] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2252; }","duration":"548.702826ms","start":"2026-05-08T18:27:55.637657Z","end":"2026-05-08T18:27:56.18636Z","steps":["trace[1688900536] 'agreement among raft nodes before linearized reading' (duration: 548.513002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:27:56.186425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:27:55.637641Z","time spent":"548.775338ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T18:27:56.186453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.66798ms","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-05-08T18:27:56.186485Z","caller":"traceutil/trace.go:171","msg":"trace[1682960831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2252; }","duration":"550.728732ms","start":"2026-05-08T18:27:55.635747Z","end":"2026-05-08T18:27:56.186476Z","steps":["trace[1682960831] 'agreement among raft nodes before linearized reading' (duration: 550.602499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:27:56.186523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:27:55.635731Z","time spent":"550.783624ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T18:27:56.186546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.003019ms","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-08T18:27:56.186322Z","caller":"traceutil/trace.go:171","msg":"trace[371623480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2252; }","duration":"349.346517ms","start":"2026-05-08T18:27:55.836956Z","end":"2026-05-08T18:27:56.186302Z","steps":["trace[371623480] 'agreement among raft nodes before linearized reading' (duration: 349.221284ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:27:56.186589Z","caller":"traceutil/trace.go:171","msg":"trace[1636294440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2252; }","duration":"547.065971ms","start":"2026-05-08T18:27:55.639509Z","end":"2026-05-08T18:27:56.186575Z","steps":["trace[1636294440] 'agreement among raft nodes before linearized reading' (duration: 546.924847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:27:56.186625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:27:55.836934Z","time spent":"349.681796ms","remote":"127.0.0.1:46918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T18:27:56.186627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:27:55.639501Z","time spent":"547.118512ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T18:27:56.186895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:27:55.62408Z","time spent":"562.164889ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}