{"level":"warn","ts":"2026-05-26T15:56:23.778879Z","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-26T15:56:23.778957Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.47: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.47:2380","--initial-cluster=instance=https://199.19.213.47:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.47:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.47: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-26T15:56:23.779104Z","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-26T15:56:23.779119Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-05-26T15:56:23.779184Z","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-26T15:56:23.779845Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"]} {"level":"info","ts":"2026-05-26T15:56:23.779912Z","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.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.47: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-26T15:56:23.787573Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.469036ms"} {"level":"info","ts":"2026-05-26T15:56:23.796818Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"944e7e1987ec6eaf","cluster-id":"cb5c24893c5cbfa7"} {"level":"info","ts":"2026-05-26T15:56:23.796926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T15:56:23.796962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 0"} {"level":"info","ts":"2026-05-26T15:56:23.796984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 944e7e1987ec6eaf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T15:56:23.796994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 1"} {"level":"info","ts":"2026-05-26T15:56:23.797038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"warn","ts":"2026-05-26T15:56:23.804679Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T15:56:23.805926Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T15:56:23.807316Z","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-26T15:56:23.811706Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"944e7e1987ec6eaf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T15:56:23.812196Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"944e7e1987ec6eaf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T15:56:23.812115Z","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-26T15:56:23.813006Z","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-26T15:56:23.813098Z","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-26T15:56:23.815398Z","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-26T15:56:23.815823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"info","ts":"2026-05-26T15:56:23.81591Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","added-peer-id":"944e7e1987ec6eaf","added-peer-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-05-26T15:56:23.816364Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-05-26T15:56:23.816376Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-05-26T15:56:23.815619Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"944e7e1987ec6eaf","initial-advertise-peer-urls":["https://199.19.213.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T15:56:23.816761Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T15:56:24.097965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T15:56:24.098056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T15:56:24.098099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgPreVoteResp from 944e7e1987ec6eaf at term 1"} {"level":"info","ts":"2026-05-26T15:56:24.098352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became candidate at term 2"} {"level":"info","ts":"2026-05-26T15:56:24.09838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgVoteResp from 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-05-26T15:56:24.098398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became leader at term 2"} {"level":"info","ts":"2026-05-26T15:56:24.098498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 944e7e1987ec6eaf elected leader 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-05-26T15:56:24.09936Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"944e7e1987ec6eaf","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.47:2379]}","request-path":"/0/members/944e7e1987ec6eaf/attributes","cluster-id":"cb5c24893c5cbfa7","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T15:56:24.099405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T15:56:24.099602Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:56:24.099779Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T15:56:24.099827Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T15:56:24.099783Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T15:56:24.100924Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:56:24.101075Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:56:24.101103Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:56:24.103192Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T15:56:24.103813Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.47:2379"} {"level":"info","ts":"2026-05-26T15:56:27.039034Z","caller":"traceutil/trace.go:171","msg":"trace[602576950] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"280.512043ms","start":"2026-05-26T15:56:26.758499Z","end":"2026-05-26T15:56:27.039011Z","steps":["trace[602576950] 'process raft request' (duration: 280.38071ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:27.039285Z","caller":"traceutil/trace.go:171","msg":"trace[2115105653] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:76; }","duration":"280.315698ms","start":"2026-05-26T15:56:26.758933Z","end":"2026-05-26T15:56:27.039249Z","steps":["trace[2115105653] 'read index received' (duration: 280.307387ms)","trace[2115105653] 'applied index is now lower than readState.Index' (duration: 6.201µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:56:27.039458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.501301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T15:56:27.039531Z","caller":"traceutil/trace.go:171","msg":"trace[107663057] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"280.606703ms","start":"2026-05-26T15:56:26.758909Z","end":"2026-05-26T15:56:27.039516Z","steps":["trace[107663057] 'agreement among raft nodes before linearized reading' (duration: 280.43844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:27.039596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.215461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T15:56:27.039667Z","caller":"traceutil/trace.go:171","msg":"trace[1272997042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"177.403475ms","start":"2026-05-26T15:56:26.862248Z","end":"2026-05-26T15:56:27.039651Z","steps":["trace[1272997042] 'agreement among raft nodes before linearized reading' (duration: 177.196221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:27.495764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.346023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-26T15:56:27.495936Z","caller":"traceutil/trace.go:171","msg":"trace[1377565780] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"451.153797ms","start":"2026-05-26T15:56:27.044708Z","end":"2026-05-26T15:56:27.495862Z","steps":["trace[1377565780] 'read index received' (duration: 316.236832ms)","trace[1377565780] 'applied index is now lower than readState.Index' (duration: 134.915225ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:56:27.495953Z","caller":"traceutil/trace.go:171","msg":"trace[1792306603] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"451.947174ms","start":"2026-05-26T15:56:27.043966Z","end":"2026-05-26T15:56:27.495913Z","steps":["trace[1792306603] 'process raft request' (duration: 316.954648ms)","trace[1792306603] 'compare' (duration: 134.214201ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:56:27.496265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.546145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T15:56:27.496362Z","caller":"traceutil/trace.go:171","msg":"trace[1583264975] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"451.692378ms","start":"2026-05-26T15:56:27.044653Z","end":"2026-05-26T15:56:27.496346Z","steps":["trace[1583264975] 'agreement among raft nodes before linearized reading' (duration: 451.379152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:27.49636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:27.043953Z","time spent":"452.087127ms","remote":"127.0.0.1:36854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T15:56:27.496436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:27.044633Z","time spent":"451.78983ms","remote":"127.0.0.1:36826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"info","ts":"2026-05-26T15:57:00.846615Z","caller":"traceutil/trace.go:171","msg":"trace[1074571166] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"206.266549ms","start":"2026-05-26T15:57:00.640324Z","end":"2026-05-26T15:57:00.84659Z","steps":["trace[1074571166] 'process raft request' (duration: 206.100035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:00.846723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.920162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:57:00.847394Z","caller":"traceutil/trace.go:171","msg":"trace[1552585339] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:489; }","duration":"196.030934ms","start":"2026-05-26T15:57:00.650729Z","end":"2026-05-26T15:57:00.84676Z","steps":["trace[1552585339] 'agreement among raft nodes before linearized reading' (duration: 195.940712ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:00.846615Z","caller":"traceutil/trace.go:171","msg":"trace[1997758079] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"195.776329ms","start":"2026-05-26T15:57:00.650783Z","end":"2026-05-26T15:57:00.846559Z","steps":["trace[1997758079] 'read index received' (duration: 195.719048ms)","trace[1997758079] 'applied index is now lower than readState.Index' (duration: 38.951µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:02.819608Z","caller":"traceutil/trace.go:171","msg":"trace[433185477] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"319.517647ms","start":"2026-05-26T15:57:02.500066Z","end":"2026-05-26T15:57:02.819584Z","steps":["trace[433185477] 'process raft request' (duration: 319.312113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:02.819751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:02.500054Z","time spent":"319.634179ms","remote":"127.0.0.1:36528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T15:57:02.831767Z","caller":"traceutil/trace.go:171","msg":"trace[608040758] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"327.224691ms","start":"2026-05-26T15:57:02.504525Z","end":"2026-05-26T15:57:02.831749Z","steps":["trace[608040758] 'process raft request' (duration: 327.16932ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:02.831808Z","caller":"traceutil/trace.go:171","msg":"trace[1755348168] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"327.400015ms","start":"2026-05-26T15:57:02.504373Z","end":"2026-05-26T15:57:02.831773Z","steps":["trace[1755348168] 'process raft request' (duration: 327.19631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:02.831874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:02.504518Z","time spent":"327.307413ms","remote":"127.0.0.1:36528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T15:57:02.832045Z","caller":"traceutil/trace.go:171","msg":"trace[1979614537] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"326.529586ms","start":"2026-05-26T15:57:02.505477Z","end":"2026-05-26T15:57:02.832007Z","steps":["trace[1979614537] 'process raft request' (duration: 326.248901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:02.832102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:02.505459Z","time spent":"326.616398ms","remote":"127.0.0.1:36528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T15:57:02.832087Z","caller":"traceutil/trace.go:171","msg":"trace[9613229] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"319.6385ms","start":"2026-05-26T15:57:02.512418Z","end":"2026-05-26T15:57:02.832056Z","steps":["trace[9613229] 'process raft request' (duration: 319.532868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:02.832248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:02.512402Z","time spent":"319.730903ms","remote":"127.0.0.1:36528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T15:57:02.832269Z","caller":"traceutil/trace.go:171","msg":"trace[714731749] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"311.876566ms","start":"2026-05-26T15:57:02.52038Z","end":"2026-05-26T15:57:02.832256Z","steps":["trace[714731749] 'process raft request' (duration: 311.659672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:02.832319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:02.520371Z","time spent":"311.920427ms","remote":"127.0.0.1:36528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T15:57:02.832382Z","caller":"traceutil/trace.go:171","msg":"trace[530497230] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"317.363325ms","start":"2026-05-26T15:57:02.515007Z","end":"2026-05-26T15:57:02.83237Z","steps":["trace[530497230] 'process raft request' (duration: 317.005368ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:02.832456Z","caller":"traceutil/trace.go:171","msg":"trace[763075794] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"292.036401ms","start":"2026-05-26T15:57:02.540406Z","end":"2026-05-26T15:57:02.832443Z","steps":["trace[763075794] 'process raft request' (duration: 291.684284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:02.832461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:02.514998Z","time spent":"317.438276ms","remote":"127.0.0.1:36528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T15:57:02.832529Z","caller":"traceutil/trace.go:171","msg":"trace[173585949] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"305.150802ms","start":"2026-05-26T15:57:02.527372Z","end":"2026-05-26T15:57:02.832523Z","steps":["trace[173585949] 'process raft request' (duration: 304.691863ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:02.832548Z","caller":"traceutil/trace.go:171","msg":"trace[171060452] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"279.538143ms","start":"2026-05-26T15:57:02.553001Z","end":"2026-05-26T15:57:02.832539Z","steps":["trace[171060452] 'process raft request' (duration: 279.289519ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:02.832551Z","caller":"traceutil/trace.go:171","msg":"trace[890882939] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"259.698678ms","start":"2026-05-26T15:57:02.572844Z","end":"2026-05-26T15:57:02.832542Z","steps":["trace[890882939] 'process raft request' (duration: 259.487914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:02.832561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:02.527357Z","time spent":"305.185482ms","remote":"127.0.0.1:36528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T15:57:02.83255Z","caller":"traceutil/trace.go:171","msg":"trace[1658329381] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"151.093171ms","start":"2026-05-26T15:57:02.681433Z","end":"2026-05-26T15:57:02.832526Z","steps":["trace[1658329381] 'process raft request' (duration: 151.03782ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:02.832616Z","caller":"traceutil/trace.go:171","msg":"trace[873215924] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"281.543243ms","start":"2026-05-26T15:57:02.551065Z","end":"2026-05-26T15:57:02.832609Z","steps":["trace[873215924] 'process raft request' (duration: 281.182996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:02.833014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:02.504357Z","time spent":"327.523027ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:57:09.146796Z","caller":"traceutil/trace.go:171","msg":"trace[323868982] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"261.282322ms","start":"2026-05-26T15:57:08.885488Z","end":"2026-05-26T15:57:09.146771Z","steps":["trace[323868982] 'read index received' (duration: 260.008346ms)","trace[323868982] 'applied index is now lower than readState.Index' (duration: 1.272796ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:09.146936Z","caller":"traceutil/trace.go:171","msg":"trace[1754236393] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"517.402169ms","start":"2026-05-26T15:57:08.629521Z","end":"2026-05-26T15:57:09.146924Z","steps":["trace[1754236393] 'process raft request' (duration: 516.204085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:09.14706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:08.629493Z","time spent":"517.47633ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:57:09.147211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.956176ms","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-26T15:57:09.147278Z","caller":"traceutil/trace.go:171","msg":"trace[861109445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"245.056168ms","start":"2026-05-26T15:57:08.902205Z","end":"2026-05-26T15:57:09.147261Z","steps":["trace[861109445] 'agreement among raft nodes before linearized reading' (duration: 244.836114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:09.147404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.886343ms","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-26T15:57:09.1475Z","caller":"traceutil/trace.go:171","msg":"trace[1220295198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"262.047996ms","start":"2026-05-26T15:57:08.885425Z","end":"2026-05-26T15:57:09.147473Z","steps":["trace[1220295198] 'agreement among raft nodes before linearized reading' (duration: 261.775341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:09.387074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.867126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:57:09.387226Z","caller":"traceutil/trace.go:171","msg":"trace[1291473883] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"233.323406ms","start":"2026-05-26T15:57:09.153867Z","end":"2026-05-26T15:57:09.38719Z","steps":["trace[1291473883] 'process raft request' (duration: 78.252605ms)","trace[1291473883] 'compare' (duration: 154.733504ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:09.38726Z","caller":"traceutil/trace.go:171","msg":"trace[1104536829] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"184.701649ms","start":"2026-05-26T15:57:09.202545Z","end":"2026-05-26T15:57:09.387247Z","steps":["trace[1104536829] 'process raft request' (duration: 184.650158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:09.387721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.071721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b6mng\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-26T15:57:09.387777Z","caller":"traceutil/trace.go:171","msg":"trace[1046617650] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b6mng; range_end:; response_count:1; response_revision:636; }","duration":"231.198573ms","start":"2026-05-26T15:57:09.156565Z","end":"2026-05-26T15:57:09.387763Z","steps":["trace[1046617650] 'agreement among raft nodes before linearized reading' (duration: 230.998389ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:09.38751Z","caller":"traceutil/trace.go:171","msg":"trace[1365204611] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"230.752975ms","start":"2026-05-26T15:57:09.156639Z","end":"2026-05-26T15:57:09.387392Z","steps":["trace[1365204611] 'read index received' (duration: 9.591421ms)","trace[1365204611] 'applied index is now lower than readState.Index' (duration: 221.159634ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:09.947636Z","caller":"traceutil/trace.go:171","msg":"trace[367559314] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"543.548028ms","start":"2026-05-26T15:57:09.404058Z","end":"2026-05-26T15:57:09.947606Z","steps":["trace[367559314] 'process raft request' (duration: 495.858751ms)","trace[367559314] 'compare' (duration: 47.530324ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:57:09.947905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:09.404035Z","time spent":"543.682561ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:57:10.002773Z","caller":"traceutil/trace.go:171","msg":"trace[156221653] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"231.930697ms","start":"2026-05-26T15:57:09.770811Z","end":"2026-05-26T15:57:10.002741Z","steps":["trace[156221653] 'process raft request' (duration: 231.212643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:10.600246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767621731580519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T15:57:11.100942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767621731580519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T15:57:11.518333Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.45675617s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T15:57:11.519068Z","caller":"traceutil/trace.go:171","msg":"trace[1393830981] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.457570606s","start":"2026-05-26T15:57:10.061466Z","end":"2026-05-26T15:57:11.519037Z","steps":["trace[1393830981] 'process raft request' (duration: 1.457086487s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:11.519216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:10.06144Z","time spent":"1.457708599s","remote":"127.0.0.1:36732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T15:57:11.519796Z","caller":"traceutil/trace.go:171","msg":"trace[1941231631] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"1.419790296s","start":"2026-05-26T15:57:10.099981Z","end":"2026-05-26T15:57:11.519771Z","steps":["trace[1941231631] 'read index received' (duration: 1.418579132s)","trace[1941231631] 'applied index is now lower than readState.Index' (duration: 1.209744ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:11.519852Z","caller":"traceutil/trace.go:171","msg":"trace[1944037667] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"640.480994ms","start":"2026-05-26T15:57:10.879358Z","end":"2026-05-26T15:57:11.519839Z","steps":["trace[1944037667] 'process raft request' (duration: 640.322421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:11.520007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42001823s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T15:57:11.520064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.807089ms","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-26T15:57:11.520207Z","caller":"traceutil/trace.go:171","msg":"trace[1164499704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"351.027214ms","start":"2026-05-26T15:57:11.169168Z","end":"2026-05-26T15:57:11.520195Z","steps":["trace[1164499704] 'agreement among raft nodes before linearized reading' (duration: 350.8087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:11.520247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:11.169148Z","time spent":"351.088865ms","remote":"127.0.0.1:36754","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-26T15:57:11.520251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257110464s","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-26T15:57:11.520299Z","caller":"traceutil/trace.go:171","msg":"trace[2006367418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.257177286s","start":"2026-05-26T15:57:10.263108Z","end":"2026-05-26T15:57:11.520285Z","steps":["trace[2006367418] 'agreement among raft nodes before linearized reading' (duration: 1.257003202s)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:11.520069Z","caller":"traceutil/trace.go:171","msg":"trace[1255630037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"1.420103462s","start":"2026-05-26T15:57:10.099948Z","end":"2026-05-26T15:57:11.520052Z","steps":["trace[1255630037] 'agreement among raft nodes before linearized reading' (duration: 1.419942169s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:11.520341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:10.2631Z","time spent":"1.257229947s","remote":"127.0.0.1:36754","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-26T15:57:11.520382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:10.099932Z","time spent":"1.420434278s","remote":"127.0.0.1:36482","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-26T15:57:11.520382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.574664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-26T15:57:11.520449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:10.879335Z","time spent":"640.549116ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:57:11.520495Z","caller":"traceutil/trace.go:171","msg":"trace[1536209938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"128.729357ms","start":"2026-05-26T15:57:11.391734Z","end":"2026-05-26T15:57:11.520463Z","steps":["trace[1536209938] 'agreement among raft nodes before linearized reading' (duration: 128.476012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:11.520185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257926519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-26T15:57:11.520894Z","caller":"traceutil/trace.go:171","msg":"trace[661834816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"1.258558552s","start":"2026-05-26T15:57:10.26215Z","end":"2026-05-26T15:57:11.520708Z","steps":["trace[661834816] 'agreement among raft nodes before linearized reading' (duration: 1.257800777s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:11.520952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:10.262129Z","time spent":"1.258806058s","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T15:57:26.838294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.145828ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T15:57:26.838342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.85714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T15:57:26.838452Z","caller":"traceutil/trace.go:171","msg":"trace[658782008] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:799; }","duration":"459.355103ms","start":"2026-05-26T15:57:26.379077Z","end":"2026-05-26T15:57:26.838432Z","steps":["trace[658782008] 'range keys from in-memory index tree' (duration: 459.132938ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:26.838517Z","caller":"traceutil/trace.go:171","msg":"trace[153835089] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:799; }","duration":"116.196568ms","start":"2026-05-26T15:57:26.722292Z","end":"2026-05-26T15:57:26.838489Z","steps":["trace[153835089] 'count revisions from in-memory index tree' (duration: 115.571515ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:47.379968Z","caller":"traceutil/trace.go:171","msg":"trace[399394637] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"146.703584ms","start":"2026-05-26T15:57:47.233239Z","end":"2026-05-26T15:57:47.379943Z","steps":["trace[399394637] 'process raft request' (duration: 146.564621ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:49.911899Z","caller":"traceutil/trace.go:171","msg":"trace[1150745924] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"106.206461ms","start":"2026-05-26T15:57:49.805665Z","end":"2026-05-26T15:57:49.911871Z","steps":["trace[1150745924] 'process raft request' (duration: 59.546895ms)","trace[1150745924] 'compare' (duration: 46.523164ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:50.255076Z","caller":"traceutil/trace.go:171","msg":"trace[1619799808] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"155.412198ms","start":"2026-05-26T15:57:50.099642Z","end":"2026-05-26T15:57:50.255054Z","steps":["trace[1619799808] 'read index received' (duration: 155.186194ms)","trace[1619799808] 'applied index is now lower than readState.Index' (duration: 225.174µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:57:50.2552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.552261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:57:50.255225Z","caller":"traceutil/trace.go:171","msg":"trace[189064136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"155.653494ms","start":"2026-05-26T15:57:50.099563Z","end":"2026-05-26T15:57:50.255217Z","steps":["trace[189064136] 'agreement among raft nodes before linearized reading' (duration: 155.577412ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:50.255183Z","caller":"traceutil/trace.go:171","msg":"trace[1188089943] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"190.88374ms","start":"2026-05-26T15:57:50.064258Z","end":"2026-05-26T15:57:50.255142Z","steps":["trace[1188089943] 'process raft request' (duration: 190.632435ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:02.733236Z","caller":"traceutil/trace.go:171","msg":"trace[980918378] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"444.367555ms","start":"2026-05-26T15:58:02.288849Z","end":"2026-05-26T15:58:02.733216Z","steps":["trace[980918378] 'process raft request' (duration: 444.267474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:02.733367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:02.288828Z","time spent":"444.471917ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:58:23.0831Z","caller":"traceutil/trace.go:171","msg":"trace[602676821] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"278.322903ms","start":"2026-05-26T15:58:22.80474Z","end":"2026-05-26T15:58:23.083063Z","steps":["trace[602676821] 'process raft request' (duration: 277.65634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:23.666187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.114504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-26T15:58:23.66621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.146247ms","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-26T15:58:23.666283Z","caller":"traceutil/trace.go:171","msg":"trace[2082267346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1083; }","duration":"369.28272ms","start":"2026-05-26T15:58:23.296985Z","end":"2026-05-26T15:58:23.666268Z","steps":["trace[2082267346] 'range keys from in-memory index tree' (duration: 368.966993ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:23.666283Z","caller":"traceutil/trace.go:171","msg":"trace[2139429676] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1083; }","duration":"497.269127ms","start":"2026-05-26T15:58:23.168992Z","end":"2026-05-26T15:58:23.666262Z","steps":["trace[2139429676] 'count revisions from in-memory index tree' (duration: 496.974041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:23.666312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:23.296961Z","time spent":"369.344371ms","remote":"127.0.0.1:36754","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-26T15:58:23.666326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:23.168969Z","time spent":"497.345979ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-05-26T15:58:25.9716Z","caller":"traceutil/trace.go:171","msg":"trace[1071480094] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"246.089196ms","start":"2026-05-26T15:58:25.72548Z","end":"2026-05-26T15:58:25.971569Z","steps":["trace[1071480094] 'process raft request' (duration: 245.866822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:26.588281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.496943ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:58:26.588659Z","caller":"traceutil/trace.go:171","msg":"trace[1338198677] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1090; }","duration":"208.890621ms","start":"2026-05-26T15:58:26.379743Z","end":"2026-05-26T15:58:26.588634Z","steps":["trace[1338198677] 'range keys from in-memory index tree' (duration: 208.478223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:26.588757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.36019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:58:26.588835Z","caller":"traceutil/trace.go:171","msg":"trace[650888984] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"559.203229ms","start":"2026-05-26T15:58:26.02962Z","end":"2026-05-26T15:58:26.588823Z","steps":["trace[650888984] 'read index received' (duration: 356.631825ms)","trace[650888984] 'applied index is now lower than readState.Index' (duration: 202.570314ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:58:26.588965Z","caller":"traceutil/trace.go:171","msg":"trace[1925214955] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"611.59098ms","start":"2026-05-26T15:58:25.977305Z","end":"2026-05-26T15:58:26.588896Z","steps":["trace[1925214955] 'process raft request' (duration: 409.012056ms)","trace[1925214955] 'compare' (duration: 201.794929ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:58:26.58903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.042249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T15:58:26.589014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.098161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T15:58:26.589078Z","caller":"traceutil/trace.go:171","msg":"trace[722586583] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1091; }","duration":"294.128961ms","start":"2026-05-26T15:58:26.294933Z","end":"2026-05-26T15:58:26.589062Z","steps":["trace[722586583] 'agreement among raft nodes before linearized reading' (duration: 294.05414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:26.589111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.57718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-26T15:58:26.589075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.441024ms","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-26T15:58:26.589117Z","caller":"traceutil/trace.go:171","msg":"trace[925616427] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1091; }","duration":"133.270145ms","start":"2026-05-26T15:58:26.455823Z","end":"2026-05-26T15:58:26.589094Z","steps":["trace[925616427] 'agreement among raft nodes before linearized reading' (duration: 133.106121ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:26.589146Z","caller":"traceutil/trace.go:171","msg":"trace[845577295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1091; }","duration":"428.631881ms","start":"2026-05-26T15:58:26.160502Z","end":"2026-05-26T15:58:26.589133Z","steps":["trace[845577295] 'agreement among raft nodes before linearized reading' (duration: 428.547508ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:26.589171Z","caller":"traceutil/trace.go:171","msg":"trace[1328986051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1091; }","duration":"559.589418ms","start":"2026-05-26T15:58:26.029556Z","end":"2026-05-26T15:58:26.589146Z","steps":["trace[1328986051] 'agreement among raft nodes before linearized reading' (duration: 559.342242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:26.589179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:26.160484Z","time spent":"428.686292ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T15:58:26.589217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:26.029533Z","time spent":"559.675909ms","remote":"127.0.0.1:36754","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-26T15:58:26.589223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:25.977245Z","time spent":"611.833854ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:58:26.589028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.98096ms","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-26T15:58:26.589403Z","caller":"traceutil/trace.go:171","msg":"trace[2087352567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1091; }","duration":"494.372948ms","start":"2026-05-26T15:58:26.095008Z","end":"2026-05-26T15:58:26.589381Z","steps":["trace[2087352567] 'agreement among raft nodes before linearized reading' (duration: 493.903918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:26.589497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:26.094988Z","time spent":"494.478561ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-26T15:58:26.707458Z","caller":"traceutil/trace.go:171","msg":"trace[1604986708] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"113.394235ms","start":"2026-05-26T15:58:26.594021Z","end":"2026-05-26T15:58:26.707416Z","steps":["trace[1604986708] 'process raft request' (duration: 111.940976ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:26.707403Z","caller":"traceutil/trace.go:171","msg":"trace[1625192675] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"113.193741ms","start":"2026-05-26T15:58:26.594176Z","end":"2026-05-26T15:58:26.70737Z","steps":["trace[1625192675] 'process raft request' (duration: 113.13173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:42.746468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.728935ms","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":"warn","ts":"2026-05-26T15:58:42.746543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.704413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:58:42.746627Z","caller":"traceutil/trace.go:171","msg":"trace[1461105225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1194; }","duration":"646.839697ms","start":"2026-05-26T15:58:42.09977Z","end":"2026-05-26T15:58:42.746609Z","steps":["trace[1461105225] 'range keys from in-memory index tree' (duration: 646.627412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:42.746655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:42.099745Z","time spent":"646.903238ms","remote":"127.0.0.1:36482","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-26T15:58:42.746757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.857623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:58:42.746808Z","caller":"traceutil/trace.go:171","msg":"trace[1483906518] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1194; }","duration":"205.956045ms","start":"2026-05-26T15:58:42.540836Z","end":"2026-05-26T15:58:42.746792Z","steps":["trace[1483906518] 'count revisions from in-memory index tree' (duration: 205.7025ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:42.746577Z","caller":"traceutil/trace.go:171","msg":"trace[1523816397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1194; }","duration":"701.875599ms","start":"2026-05-26T15:58:42.044679Z","end":"2026-05-26T15:58:42.746555Z","steps":["trace[1523816397] 'range keys from in-memory index tree' (duration: 701.524182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:42.746845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:42.04465Z","time spent":"702.185906ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-26T15:58:58.452273Z","caller":"traceutil/trace.go:171","msg":"trace[810643059] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1307; }","duration":"121.739849ms","start":"2026-05-26T15:58:58.330514Z","end":"2026-05-26T15:58:58.452254Z","steps":["trace[810643059] 'read index received' (duration: 121.565755ms)","trace[810643059] 'applied index is now lower than readState.Index' (duration: 173.294µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:58:58.45232Z","caller":"traceutil/trace.go:171","msg":"trace[1262470401] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"219.832153ms","start":"2026-05-26T15:58:58.232459Z","end":"2026-05-26T15:58:58.452291Z","steps":["trace[1262470401] 'process raft request' (duration: 219.66259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:58.452447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.905212ms","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-26T15:58:58.452519Z","caller":"traceutil/trace.go:171","msg":"trace[1338387015] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1261; }","duration":"122.058094ms","start":"2026-05-26T15:58:58.330445Z","end":"2026-05-26T15:58:58.452503Z","steps":["trace[1338387015] 'agreement among raft nodes before linearized reading' (duration: 121.914602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:59.01682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.685485ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-26T15:58:59.01693Z","caller":"traceutil/trace.go:171","msg":"trace[1226945190] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"560.561247ms","start":"2026-05-26T15:58:58.456349Z","end":"2026-05-26T15:58:59.016911Z","steps":["trace[1226945190] 'read index received' (duration: 163.74025ms)","trace[1226945190] 'applied index is now lower than readState.Index' (duration: 396.819327ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:58:59.016966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:58.453686Z","time spent":"563.259212ms","remote":"127.0.0.1:36510","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-26T15:58:59.01708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.72184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-26T15:58:59.017112Z","caller":"traceutil/trace.go:171","msg":"trace[29875530] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1261; }","duration":"560.756791ms","start":"2026-05-26T15:58:58.456344Z","end":"2026-05-26T15:58:59.017101Z","steps":["trace[29875530] 'agreement among raft nodes before linearized reading' (duration: 560.638399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:59.017144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:58.456291Z","time spent":"560.844383ms","remote":"127.0.0.1:36898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-26T15:58:59.061171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.960784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-djmn5\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-05-26T15:58:59.061256Z","caller":"traceutil/trace.go:171","msg":"trace[1951465394] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-djmn5; range_end:; response_count:1; response_revision:1262; }","duration":"461.112037ms","start":"2026-05-26T15:58:58.600125Z","end":"2026-05-26T15:58:59.061237Z","steps":["trace[1951465394] 'agreement among raft nodes before linearized reading' (duration: 460.846911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:59.061304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:58.600109Z","time spent":"461.182918ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11081,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-djmn5\" "} {"level":"info","ts":"2026-05-26T15:58:59.061783Z","caller":"traceutil/trace.go:171","msg":"trace[349993131] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"292.078951ms","start":"2026-05-26T15:58:58.769686Z","end":"2026-05-26T15:58:59.061765Z","steps":["trace[349993131] 'process raft request' (duration: 291.02625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:59.062096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.401402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:58:59.06215Z","caller":"traceutil/trace.go:171","msg":"trace[1237922795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"136.484153ms","start":"2026-05-26T15:58:58.925653Z","end":"2026-05-26T15:58:59.062137Z","steps":["trace[1237922795] 'agreement among raft nodes before linearized reading' (duration: 136.403272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:59.062455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.504336ms","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-26T15:58:59.062514Z","caller":"traceutil/trace.go:171","msg":"trace[240080606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1262; }","duration":"181.585107ms","start":"2026-05-26T15:58:58.880915Z","end":"2026-05-26T15:58:59.0625Z","steps":["trace[240080606] 'agreement among raft nodes before linearized reading' (duration: 181.437373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:59.062619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.620266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:58:59.062686Z","caller":"traceutil/trace.go:171","msg":"trace[1527634211] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1262; }","duration":"443.713677ms","start":"2026-05-26T15:58:58.618952Z","end":"2026-05-26T15:58:59.062666Z","steps":["trace[1527634211] 'agreement among raft nodes before linearized reading' (duration: 443.603405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:59.062733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:58.618941Z","time spent":"443.776968ms","remote":"127.0.0.1:50914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-05-26T15:58:59.063024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.1842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-26T15:58:59.063087Z","caller":"traceutil/trace.go:171","msg":"trace[924757673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1262; }","duration":"211.247031ms","start":"2026-05-26T15:58:58.851826Z","end":"2026-05-26T15:58:59.063073Z","steps":["trace[924757673] 'agreement among raft nodes before linearized reading' (duration: 211.101868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:59.063094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.118208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-26T15:58:59.063033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.74868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-26T15:58:59.063167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.813942ms","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-26T15:58:59.063219Z","caller":"traceutil/trace.go:171","msg":"trace[1779430534] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1262; }","duration":"183.961345ms","start":"2026-05-26T15:58:58.879245Z","end":"2026-05-26T15:58:59.063206Z","steps":["trace[1779430534] 'agreement among raft nodes before linearized reading' (duration: 183.70281ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:59.063231Z","caller":"traceutil/trace.go:171","msg":"trace[1369875945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1262; }","duration":"185.899564ms","start":"2026-05-26T15:58:58.877318Z","end":"2026-05-26T15:58:59.063218Z","steps":["trace[1369875945] 'agreement among raft nodes before linearized reading' (duration: 185.757141ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:59.063178Z","caller":"traceutil/trace.go:171","msg":"trace[1692861945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1262; }","duration":"192.24077ms","start":"2026-05-26T15:58:58.870917Z","end":"2026-05-26T15:58:59.063158Z","steps":["trace[1692861945] 'agreement among raft nodes before linearized reading' (duration: 191.994145ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:59.207364Z","caller":"traceutil/trace.go:171","msg":"trace[1607790615] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1312; }","duration":"134.27482ms","start":"2026-05-26T15:58:59.073068Z","end":"2026-05-26T15:58:59.207343Z","steps":["trace[1607790615] 'read index received' (duration: 22.212565ms)","trace[1607790615] 'applied index is now lower than readState.Index' (duration: 112.061325ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:58:59.207499Z","caller":"traceutil/trace.go:171","msg":"trace[943066571] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"136.696049ms","start":"2026-05-26T15:58:59.070786Z","end":"2026-05-26T15:58:59.207482Z","steps":["trace[943066571] 'process raft request' (duration: 100.411882ms)","trace[943066571] 'compare' (duration: 35.94733ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:58:59.207587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.499254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-26T15:58:59.207683Z","caller":"traceutil/trace.go:171","msg":"trace[2060588428] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1270; }","duration":"134.622476ms","start":"2026-05-26T15:58:59.073044Z","end":"2026-05-26T15:58:59.207666Z","steps":["trace[2060588428] 'agreement among raft nodes before linearized reading' (duration: 134.452603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:59.207739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.067025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:58:59.207837Z","caller":"traceutil/trace.go:171","msg":"trace[1216444053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"109.218388ms","start":"2026-05-26T15:58:59.098603Z","end":"2026-05-26T15:58:59.207822Z","steps":["trace[1216444053] 'agreement among raft nodes before linearized reading' (duration: 109.020274ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:59.207917Z","caller":"traceutil/trace.go:171","msg":"trace[1725563780] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"132.83265ms","start":"2026-05-26T15:58:59.075071Z","end":"2026-05-26T15:58:59.207903Z","steps":["trace[1725563780] 'process raft request' (duration: 132.213078ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:59:01.684709Z","caller":"traceutil/trace.go:171","msg":"trace[495031383] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"573.574308ms","start":"2026-05-26T15:59:01.111103Z","end":"2026-05-26T15:59:01.684677Z","steps":["trace[495031383] 'process raft request' (duration: 573.298623ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:59:01.684744Z","caller":"traceutil/trace.go:171","msg":"trace[133110102] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1324; }","duration":"471.716418ms","start":"2026-05-26T15:59:01.213001Z","end":"2026-05-26T15:59:01.684717Z","steps":["trace[133110102] 'read index received' (duration: 471.402262ms)","trace[133110102] 'applied index is now lower than readState.Index' (duration: 313.096µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:59:01.684864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:59:01.111075Z","time spent":"573.712161ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:59:01.684942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.915893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-26T15:59:01.684988Z","caller":"traceutil/trace.go:171","msg":"trace[691587164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1279; }","duration":"472.022325ms","start":"2026-05-26T15:59:01.212952Z","end":"2026-05-26T15:59:01.684975Z","steps":["trace[691587164] 'agreement among raft nodes before linearized reading' (duration: 471.865941ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:59:01.685032Z","caller":"traceutil/trace.go:171","msg":"trace[484181229] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"573.920935ms","start":"2026-05-26T15:59:01.111105Z","end":"2026-05-26T15:59:01.685026Z","steps":["trace[484181229] 'process raft request' (duration: 573.464725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:59:01.685032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:59:01.212931Z","time spent":"472.089337ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T15:59:01.685064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:59:01.111074Z","time spent":"573.971357ms","remote":"127.0.0.1:36754","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-26T15:59:01.685178Z","caller":"traceutil/trace.go:171","msg":"trace[1932055017] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"573.953717ms","start":"2026-05-26T15:59:01.111201Z","end":"2026-05-26T15:59:01.685155Z","steps":["trace[1932055017] 'process raft request' (duration: 573.478016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:59:01.685331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:59:01.11119Z","time spent":"574.034748ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:59:01.879732Z","caller":"traceutil/trace.go:171","msg":"trace[951265239] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"188.723371ms","start":"2026-05-26T15:59:01.690976Z","end":"2026-05-26T15:59:01.879699Z","steps":["trace[951265239] 'process raft request' (duration: 188.67918ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:59:01.87978Z","caller":"traceutil/trace.go:171","msg":"trace[237821471] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"493.328662ms","start":"2026-05-26T15:59:01.386408Z","end":"2026-05-26T15:59:01.879737Z","steps":["trace[237821471] 'process raft request' (duration: 431.418101ms)","trace[237821471] 'compare' (duration: 61.691437ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:59:01.879971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:59:01.386383Z","time spent":"493.482825ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:59:12.520566Z","caller":"traceutil/trace.go:171","msg":"trace[1664066209] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"101.360621ms","start":"2026-05-26T15:59:12.419176Z","end":"2026-05-26T15:59:12.520537Z","steps":["trace[1664066209] 'process raft request' (duration: 101.162726ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:59:12.881592Z","caller":"traceutil/trace.go:171","msg":"trace[1142927598] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"462.237429ms","start":"2026-05-26T15:59:12.419325Z","end":"2026-05-26T15:59:12.881562Z","steps":["trace[1142927598] 'process raft request' (duration: 379.506832ms)","trace[1142927598] 'compare' (duration: 82.633705ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:59:12.881772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:59:12.419291Z","time spent":"462.390171ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:59:13.715828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.575339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:59:13.715926Z","caller":"traceutil/trace.go:171","msg":"trace[997661359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1362; }","duration":"615.766423ms","start":"2026-05-26T15:59:13.100138Z","end":"2026-05-26T15:59:13.715904Z","steps":["trace[997661359] 'range keys from in-memory index tree' (duration: 615.431867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:59:13.715981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:59:13.100076Z","time spent":"615.895307ms","remote":"127.0.0.1:36482","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-26T15:59:13.715949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.125286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T15:59:13.716056Z","caller":"traceutil/trace.go:171","msg":"trace[1157504061] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1362; }","duration":"156.29431ms","start":"2026-05-26T15:59:13.559736Z","end":"2026-05-26T15:59:13.716031Z","steps":["trace[1157504061] 'count revisions from in-memory index tree' (duration: 156.021914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:59:13.716075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.842551ms","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-26T15:59:13.716128Z","caller":"traceutil/trace.go:171","msg":"trace[1380694402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1362; }","duration":"512.989774ms","start":"2026-05-26T15:59:13.203122Z","end":"2026-05-26T15:59:13.716112Z","steps":["trace[1380694402] 'range keys from in-memory index tree' (duration: 512.623508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:59:13.716211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:59:13.20309Z","time spent":"513.102687ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T15:59:13.715952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.251388ms","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-26T15:59:13.716422Z","caller":"traceutil/trace.go:171","msg":"trace[1452116526] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1362; }","duration":"118.775389ms","start":"2026-05-26T15:59:13.597625Z","end":"2026-05-26T15:59:13.7164Z","steps":["trace[1452116526] 'range keys from in-memory index tree' (duration: 117.978703ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:49.013362Z","caller":"traceutil/trace.go:171","msg":"trace[2078446027] linearizableReadLoop","detail":"{readStateIndex:2161; appliedIndex:2160; }","duration":"142.404601ms","start":"2026-05-26T16:01:48.870932Z","end":"2026-05-26T16:01:49.013337Z","steps":["trace[2078446027] 'read index received' (duration: 105.094023ms)","trace[2078446027] 'applied index is now lower than readState.Index' (duration: 37.309368ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:49.013572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.440383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-26T16:01:49.013592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.647117ms","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-26T16:01:49.013653Z","caller":"traceutil/trace.go:171","msg":"trace[1620662076] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2079; }","duration":"135.545186ms","start":"2026-05-26T16:01:48.878068Z","end":"2026-05-26T16:01:49.013613Z","steps":["trace[1620662076] 'agreement among raft nodes before linearized reading' (duration: 135.447724ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:49.013674Z","caller":"traceutil/trace.go:171","msg":"trace[1858240584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2079; }","duration":"142.762358ms","start":"2026-05-26T16:01:48.870897Z","end":"2026-05-26T16:01:49.01366Z","steps":["trace[1858240584] 'agreement among raft nodes before linearized reading' (duration: 142.560543ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:49.013587Z","caller":"traceutil/trace.go:171","msg":"trace[1866273681] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"142.720137ms","start":"2026-05-26T16:01:48.870822Z","end":"2026-05-26T16:01:49.013543Z","steps":["trace[1866273681] 'process raft request' (duration: 105.247025ms)","trace[1866273681] 'compare' (duration: 37.156956ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:02:59.457951Z","caller":"traceutil/trace.go:171","msg":"trace[1917193988] linearizableReadLoop","detail":"{readStateIndex:2518; appliedIndex:2517; }","duration":"118.286377ms","start":"2026-05-26T16:02:59.339648Z","end":"2026-05-26T16:02:59.457934Z","steps":["trace[1917193988] 'read index received' (duration: 118.180045ms)","trace[1917193988] 'applied index is now lower than readState.Index' (duration: 105.282µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:02:59.458068Z","caller":"traceutil/trace.go:171","msg":"trace[1652324327] transaction","detail":"{read_only:false; response_revision:2421; number_of_response:1; }","duration":"159.673916ms","start":"2026-05-26T16:02:59.298351Z","end":"2026-05-26T16:02:59.458025Z","steps":["trace[1652324327] 'process raft request' (duration: 159.460422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:02:59.458122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.464991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-26T16:02:59.458191Z","caller":"traceutil/trace.go:171","msg":"trace[617256381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2421; }","duration":"118.546893ms","start":"2026-05-26T16:02:59.339635Z","end":"2026-05-26T16:02:59.458182Z","steps":["trace[617256381] 'agreement among raft nodes before linearized reading' (duration: 118.40646ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:03:08.388832Z","caller":"traceutil/trace.go:171","msg":"trace[481022438] transaction","detail":"{read_only:false; response_revision:2467; number_of_response:1; }","duration":"128.687781ms","start":"2026-05-26T16:03:08.260103Z","end":"2026-05-26T16:03:08.388791Z","steps":["trace[481022438] 'process raft request' (duration: 128.224661ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:03:08.768055Z","caller":"traceutil/trace.go:171","msg":"trace[238649658] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"277.507085ms","start":"2026-05-26T16:03:08.490516Z","end":"2026-05-26T16:03:08.768023Z","steps":["trace[238649658] 'process raft request' (duration: 189.295794ms)","trace[238649658] 'compare' (duration: 87.886204ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:03:13.743289Z","caller":"traceutil/trace.go:171","msg":"trace[1997943614] linearizableReadLoop","detail":"{readStateIndex:2596; appliedIndex:2594; }","duration":"182.907609ms","start":"2026-05-26T16:03:13.56036Z","end":"2026-05-26T16:03:13.743267Z","steps":["trace[1997943614] 'read index received' (duration: 30.120649ms)","trace[1997943614] 'applied index is now lower than readState.Index' (duration: 152.78585ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:03:13.743378Z","caller":"traceutil/trace.go:171","msg":"trace[552020100] transaction","detail":"{read_only:false; response_revision:2497; number_of_response:1; }","duration":"184.781425ms","start":"2026-05-26T16:03:13.558561Z","end":"2026-05-26T16:03:13.743342Z","steps":["trace[552020100] 'process raft request' (duration: 184.575421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:03:13.743459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.073482ms","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-26T16:03:13.743489Z","caller":"traceutil/trace.go:171","msg":"trace[1169532247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2497; }","duration":"183.186084ms","start":"2026-05-26T16:03:13.560297Z","end":"2026-05-26T16:03:13.743483Z","steps":["trace[1169532247] 'agreement among raft nodes before linearized reading' (duration: 183.065072ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:03:44.744987Z","caller":"traceutil/trace.go:171","msg":"trace[198780] linearizableReadLoop","detail":"{readStateIndex:2748; appliedIndex:2747; }","duration":"115.848161ms","start":"2026-05-26T16:03:44.629108Z","end":"2026-05-26T16:03:44.744956Z","steps":["trace[198780] 'read index received' (duration: 78.162545ms)","trace[198780] 'applied index is now lower than readState.Index' (duration: 37.684266ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:03:44.745018Z","caller":"traceutil/trace.go:171","msg":"trace[1885188601] transaction","detail":"{read_only:false; response_revision:2642; number_of_response:1; }","duration":"122.316636ms","start":"2026-05-26T16:03:44.622678Z","end":"2026-05-26T16:03:44.744995Z","steps":["trace[1885188601] 'process raft request' (duration: 84.619301ms)","trace[1885188601] 'compare' (duration: 37.482641ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:03:44.745237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.101405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-26T16:03:44.745351Z","caller":"traceutil/trace.go:171","msg":"trace[1561031161] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:1; response_revision:2642; }","duration":"116.243718ms","start":"2026-05-26T16:03:44.629084Z","end":"2026-05-26T16:03:44.745328Z","steps":["trace[1561031161] 'agreement among raft nodes before linearized reading' (duration: 116.015993ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:03:45.33769Z","caller":"traceutil/trace.go:171","msg":"trace[1418038922] transaction","detail":"{read_only:false; response_revision:2643; number_of_response:1; }","duration":"102.530651ms","start":"2026-05-26T16:03:45.235128Z","end":"2026-05-26T16:03:45.337658Z","steps":["trace[1418038922] 'process raft request' (duration: 102.325087ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:03:45.41135Z","caller":"traceutil/trace.go:171","msg":"trace[383884385] linearizableReadLoop","detail":"{readStateIndex:2750; appliedIndex:2748; }","duration":"153.983155ms","start":"2026-05-26T16:03:45.257337Z","end":"2026-05-26T16:03:45.41132Z","steps":["trace[383884385] 'read index received' (duration: 80.193934ms)","trace[383884385] 'applied index is now lower than readState.Index' (duration: 73.788161ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:03:45.411489Z","caller":"traceutil/trace.go:171","msg":"trace[978192701] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"169.016968ms","start":"2026-05-26T16:03:45.242449Z","end":"2026-05-26T16:03:45.411466Z","steps":["trace[978192701] 'process raft request' (duration: 131.293642ms)","trace[978192701] 'compare' (duration: 37.475542ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:03:45.411649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.077582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" ","response":"range_response_count:1 size:1189"} {"level":"info","ts":"2026-05-26T16:03:45.411694Z","caller":"traceutil/trace.go:171","msg":"trace[467817876] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-nodeplugin-http-metrics; range_end:; response_count:1; response_revision:2644; }","duration":"121.175865ms","start":"2026-05-26T16:03:45.290505Z","end":"2026-05-26T16:03:45.411681Z","steps":["trace[467817876] 'agreement among raft nodes before linearized reading' (duration: 121.003211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:03:45.41175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.368102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics\" ","response":"range_response_count:1 size:1193"} {"level":"info","ts":"2026-05-26T16:03:45.411875Z","caller":"traceutil/trace.go:171","msg":"trace[1072043289] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics; range_end:; response_count:1; response_revision:2644; }","duration":"154.543366ms","start":"2026-05-26T16:03:45.257297Z","end":"2026-05-26T16:03:45.41184Z","steps":["trace[1072043289] 'agreement among raft nodes before linearized reading' (duration: 154.210619ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:03:46.004942Z","caller":"traceutil/trace.go:171","msg":"trace[1551931527] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"250.07332ms","start":"2026-05-26T16:03:45.754829Z","end":"2026-05-26T16:03:46.004902Z","steps":["trace[1551931527] 'process raft request' (duration: 249.841336ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:03:46.026687Z","caller":"traceutil/trace.go:171","msg":"trace[1214913274] linearizableReadLoop","detail":"{readStateIndex:2753; appliedIndex:2751; }","duration":"106.473697ms","start":"2026-05-26T16:03:45.920179Z","end":"2026-05-26T16:03:46.026653Z","steps":["trace[1214913274] 'read index received' (duration: 84.56514ms)","trace[1214913274] 'applied index is now lower than readState.Index' (duration: 21.906357ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:03:46.026734Z","caller":"traceutil/trace.go:171","msg":"trace[163828872] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"270.862455ms","start":"2026-05-26T16:03:45.755839Z","end":"2026-05-26T16:03:46.026701Z","steps":["trace[163828872] 'process raft request' (duration: 270.664061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:03:46.02702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.798134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-26T16:03:46.027348Z","caller":"traceutil/trace.go:171","msg":"trace[1202098104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2647; }","duration":"107.191772ms","start":"2026-05-26T16:03:45.920104Z","end":"2026-05-26T16:03:46.027296Z","steps":["trace[1202098104] 'agreement among raft nodes before linearized reading' (duration: 106.710312ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:03:46.965457Z","caller":"traceutil/trace.go:171","msg":"trace[445706180] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"211.806953ms","start":"2026-05-26T16:03:46.753611Z","end":"2026-05-26T16:03:46.965418Z","steps":["trace[445706180] 'process raft request' (duration: 211.569809ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:03:47.124764Z","caller":"traceutil/trace.go:171","msg":"trace[1356195625] transaction","detail":"{read_only:false; response_revision:2652; number_of_response:1; }","duration":"153.802851ms","start":"2026-05-26T16:03:46.97092Z","end":"2026-05-26T16:03:47.124723Z","steps":["trace[1356195625] 'process raft request' (duration: 138.211626ms)","trace[1356195625] 'compare' (duration: 15.266858ms)"],"step_count":2}