{"level":"warn","ts":"2026-07-23T10:32:35.289283Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T10:32:35.289365Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.104: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.104:2380","--initial-cluster=instance=https://199.19.213.104:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.104:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.104: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-07-23T10:32:35.289424Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T10:32:35.289432Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.104:2380"]} {"level":"info","ts":"2026-07-23T10:32:35.289448Z","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-07-23T10:32:35.290165Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.104:2379"]} {"level":"info","ts":"2026-07-23T10:32:35.290243Z","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.104:2380"],"listen-peer-urls":["https://199.19.213.104:2380"],"advertise-client-urls":["https://199.19.213.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.104: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-07-23T10:32:35.292942Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.511411ms"} {"level":"info","ts":"2026-07-23T10:32:35.300422Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8a0c9a032e61a05a","cluster-id":"153e4a99345578f6"} {"level":"info","ts":"2026-07-23T10:32:35.300503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T10:32:35.300529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became follower at term 0"} {"level":"info","ts":"2026-07-23T10:32:35.300545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8a0c9a032e61a05a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T10:32:35.300554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became follower at term 1"} {"level":"info","ts":"2026-07-23T10:32:35.300597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a switched to configuration voters=(9947495015408312410)"} {"level":"warn","ts":"2026-07-23T10:32:35.305539Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T10:32:35.307964Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T10:32:35.309275Z","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-07-23T10:32:35.314813Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8a0c9a032e61a05a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T10:32:35.315608Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8a0c9a032e61a05a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T10:32:35.3158Z","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-07-23T10:32:35.315908Z","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-07-23T10:32:35.315925Z","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-07-23T10:32:35.318138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a switched to configuration voters=(9947495015408312410)"} {"level":"info","ts":"2026-07-23T10:32:35.320413Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"153e4a99345578f6","local-member-id":"8a0c9a032e61a05a","added-peer-id":"8a0c9a032e61a05a","added-peer-peer-urls":["https://199.19.213.104:2380"]} {"level":"info","ts":"2026-07-23T10:32:35.321665Z","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-07-23T10:32:35.321936Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8a0c9a032e61a05a","initial-advertise-peer-urls":["https://199.19.213.104:2380"],"listen-peer-urls":["https://199.19.213.104:2380"],"advertise-client-urls":["https://199.19.213.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T10:32:35.321982Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T10:32:35.322347Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.104:2380"} {"level":"info","ts":"2026-07-23T10:32:35.322388Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.104:2380"} {"level":"info","ts":"2026-07-23T10:32:35.701709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T10:32:35.701779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T10:32:35.701814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a received MsgPreVoteResp from 8a0c9a032e61a05a at term 1"} {"level":"info","ts":"2026-07-23T10:32:35.70183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became candidate at term 2"} {"level":"info","ts":"2026-07-23T10:32:35.70184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a received MsgVoteResp from 8a0c9a032e61a05a at term 2"} {"level":"info","ts":"2026-07-23T10:32:35.701858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became leader at term 2"} {"level":"info","ts":"2026-07-23T10:32:35.701869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8a0c9a032e61a05a elected leader 8a0c9a032e61a05a at term 2"} {"level":"info","ts":"2026-07-23T10:32:35.703433Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T10:32:35.703435Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8a0c9a032e61a05a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.104:2379]}","request-path":"/0/members/8a0c9a032e61a05a/attributes","cluster-id":"153e4a99345578f6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T10:32:35.703476Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T10:32:35.703677Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T10:32:35.703903Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T10:32:35.703961Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T10:32:35.704585Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"153e4a99345578f6","local-member-id":"8a0c9a032e61a05a","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T10:32:35.704823Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T10:32:35.704979Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T10:32:35.70759Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.104:2379"} {"level":"info","ts":"2026-07-23T10:32:35.707594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-23T10:32:37.112623Z","caller":"traceutil/trace.go:171","msg":"trace[1600827060] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"124.070951ms","start":"2026-07-23T10:32:36.988534Z","end":"2026-07-23T10:32:37.112605Z","steps":["trace[1600827060] 'process raft request' (duration: 123.824074ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:32:37.112617Z","caller":"traceutil/trace.go:171","msg":"trace[1961734205] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"122.874717ms","start":"2026-07-23T10:32:36.989717Z","end":"2026-07-23T10:32:37.112591Z","steps":["trace[1961734205] 'read index received' (duration: 122.764694ms)","trace[1961734205] 'applied index is now lower than readState.Index' (duration: 109.093µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:32:37.112812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.038982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-23T10:32:37.112939Z","caller":"traceutil/trace.go:171","msg":"trace[1574910235] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"123.226187ms","start":"2026-07-23T10:32:36.989701Z","end":"2026-07-23T10:32:37.112927Z","steps":["trace[1574910235] 'agreement among raft nodes before linearized reading' (duration: 122.97979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:32:37.136807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.78783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-23T10:32:37.137043Z","caller":"traceutil/trace.go:171","msg":"trace[1327376571] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"147.085168ms","start":"2026-07-23T10:32:36.989911Z","end":"2026-07-23T10:32:37.136996Z","steps":["trace[1327376571] 'agreement among raft nodes before linearized reading' (duration: 145.967827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:32:37.137829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.177353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-23T10:32:37.137954Z","caller":"traceutil/trace.go:171","msg":"trace[1018844681] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"146.309027ms","start":"2026-07-23T10:32:36.991624Z","end":"2026-07-23T10:32:37.137933Z","steps":["trace[1018844681] 'agreement among raft nodes before linearized reading' (duration: 146.160042ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:32:57.772659Z","caller":"traceutil/trace.go:171","msg":"trace[881600063] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"178.206944ms","start":"2026-07-23T10:32:57.594423Z","end":"2026-07-23T10:32:57.77263Z","steps":["trace[881600063] 'process raft request' (duration: 178.068501ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:33:23.591985Z","caller":"traceutil/trace.go:171","msg":"trace[2041081835] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"137.616965ms","start":"2026-07-23T10:33:23.454335Z","end":"2026-07-23T10:33:23.591952Z","steps":["trace[2041081835] 'process raft request' (duration: 136.700129ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:33:28.856625Z","caller":"traceutil/trace.go:171","msg":"trace[169932771] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"437.469299ms","start":"2026-07-23T10:33:28.419128Z","end":"2026-07-23T10:33:28.856597Z","steps":["trace[169932771] 'process raft request' (duration: 437.264813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:33:28.857504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:33:28.419107Z","time spent":"437.598473ms","remote":"127.0.0.1:41578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:33:29.179652Z","caller":"traceutil/trace.go:171","msg":"trace[1295201562] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"629.925839ms","start":"2026-07-23T10:33:28.549698Z","end":"2026-07-23T10:33:29.179624Z","steps":["trace[1295201562] 'process raft request' (duration: 629.199678ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:33:29.179815Z","caller":"traceutil/trace.go:171","msg":"trace[292515908] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"423.005443ms","start":"2026-07-23T10:33:28.756773Z","end":"2026-07-23T10:33:29.179778Z","steps":["trace[292515908] 'process raft request' (duration: 422.794367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:33:29.17983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:33:28.549662Z","time spent":"630.070972ms","remote":"127.0.0.1:41476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:33:29.179918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:33:28.756745Z","time spent":"423.122564ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:33:29.957319Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11554723228501076325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T10:33:30.443817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.710037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T10:33:30.444113Z","caller":"traceutil/trace.go:171","msg":"trace[1188805564] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.070931447s","start":"2026-07-23T10:33:29.373124Z","end":"2026-07-23T10:33:30.444055Z","steps":["trace[1188805564] 'process raft request' (duration: 617.18673ms)","trace[1188805564] 'compare' (duration: 452.521043ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:33:30.444233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:33:29.373097Z","time spent":"1.07108074s","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:33:30.446786Z","caller":"traceutil/trace.go:171","msg":"trace[36055247] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:683; }","duration":"990.210084ms","start":"2026-07-23T10:33:29.456555Z","end":"2026-07-23T10:33:30.446765Z","steps":["trace[36055247] 'read index received' (duration: 533.821662ms)","trace[36055247] 'applied index is now lower than readState.Index' (duration: 456.386922ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:33:30.446919Z","caller":"traceutil/trace.go:171","msg":"trace[1255612176] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"720.106729ms","start":"2026-07-23T10:33:29.726803Z","end":"2026-07-23T10:33:30.44691Z","steps":["trace[1255612176] 'process raft request' (duration: 718.109532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:33:30.447018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:33:29.726773Z","time spent":"720.171849ms","remote":"127.0.0.1:41476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:33:30.448798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.921568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-23T10:33:30.448891Z","caller":"traceutil/trace.go:171","msg":"trace[293895243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:663; }","duration":"820.066113ms","start":"2026-07-23T10:33:29.628813Z","end":"2026-07-23T10:33:30.448879Z","steps":["trace[293895243] 'agreement among raft nodes before linearized reading' (duration: 818.254772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:33:30.448934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:33:29.628796Z","time spent":"820.128714ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T10:33:30.449287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.002544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-23T10:33:30.449381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.150445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-23T10:33:30.449605Z","caller":"traceutil/trace.go:171","msg":"trace[1357717118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"503.261502ms","start":"2026-07-23T10:33:29.946183Z","end":"2026-07-23T10:33:30.449445Z","steps":["trace[1357717118] 'agreement among raft nodes before linearized reading' (duration: 502.83976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:33:30.451308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:33:29.946148Z","time spent":"505.145805ms","remote":"127.0.0.1:41646","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-07-23T10:33:30.449383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.830738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.104\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-23T10:33:30.451808Z","caller":"traceutil/trace.go:171","msg":"trace[1996620654] range","detail":"{range_begin:/registry/masterleases/199.19.213.104; range_end:; response_count:1; response_revision:663; }","duration":"995.309848ms","start":"2026-07-23T10:33:29.456483Z","end":"2026-07-23T10:33:30.451793Z","steps":["trace[1996620654] 'agreement among raft nodes before linearized reading' (duration: 992.845719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:33:30.45193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:33:29.456454Z","time spent":"995.461342ms","remote":"127.0.0.1:41420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.104\" "} {"level":"info","ts":"2026-07-23T10:33:30.449569Z","caller":"traceutil/trace.go:171","msg":"trace[1751448474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:663; }","duration":"820.32105ms","start":"2026-07-23T10:33:29.629178Z","end":"2026-07-23T10:33:30.449499Z","steps":["trace[1751448474] 'agreement among raft nodes before linearized reading' (duration: 819.975989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:33:30.455516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:33:29.629128Z","time spent":"826.325079ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T10:33:34.803616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.576051ms","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-07-23T10:33:34.803877Z","caller":"traceutil/trace.go:171","msg":"trace[653955790] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:715; }","duration":"150.853079ms","start":"2026-07-23T10:33:34.653002Z","end":"2026-07-23T10:33:34.803855Z","steps":["trace[653955790] 'range keys from in-memory index tree' (duration: 150.558289ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:33:34.803713Z","caller":"traceutil/trace.go:171","msg":"trace[1701061386] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"261.963987ms","start":"2026-07-23T10:33:34.541724Z","end":"2026-07-23T10:33:34.803688Z","steps":["trace[1701061386] 'process raft request' (duration: 258.701865ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:33:34.817653Z","caller":"traceutil/trace.go:171","msg":"trace[1890020652] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"121.294975ms","start":"2026-07-23T10:33:34.696339Z","end":"2026-07-23T10:33:34.817634Z","steps":["trace[1890020652] 'process raft request' (duration: 121.218232ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:34:21.069773Z","caller":"traceutil/trace.go:171","msg":"trace[512016200] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"329.974562ms","start":"2026-07-23T10:34:20.739769Z","end":"2026-07-23T10:34:21.069743Z","steps":["trace[512016200] 'process raft request' (duration: 329.841028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:34:21.069919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:34:20.739742Z","time spent":"330.111556ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:34:21.394464Z","caller":"traceutil/trace.go:171","msg":"trace[927551975] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"654.456658ms","start":"2026-07-23T10:34:20.739983Z","end":"2026-07-23T10:34:21.39444Z","steps":["trace[927551975] 'process raft request' (duration: 622.304025ms)","trace[927551975] 'compare' (duration: 31.965409ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:34:21.394576Z","caller":"traceutil/trace.go:171","msg":"trace[1146917149] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"654.454429ms","start":"2026-07-23T10:34:20.74011Z","end":"2026-07-23T10:34:21.394565Z","steps":["trace[1146917149] 'process raft request' (duration: 654.273853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:34:21.394605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:34:20.739952Z","time spent":"654.584702ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:34:21.394639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:34:20.740105Z","time spent":"654.499459ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:34:21.394814Z","caller":"traceutil/trace.go:171","msg":"trace[455928290] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"653.176081ms","start":"2026-07-23T10:34:20.741632Z","end":"2026-07-23T10:34:21.394808Z","steps":["trace[455928290] 'process raft request' (duration: 652.887493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:34:21.394857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:34:20.741606Z","time spent":"653.226484ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:34:21.395014Z","caller":"traceutil/trace.go:171","msg":"trace[1455613287] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"606.623728ms","start":"2026-07-23T10:34:20.788349Z","end":"2026-07-23T10:34:21.394972Z","steps":["trace[1455613287] 'process raft request' (duration: 606.431284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:34:21.395172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:34:20.788328Z","time spent":"606.762564ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:34:43.163946Z","caller":"traceutil/trace.go:171","msg":"trace[59800609] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"311.158158ms","start":"2026-07-23T10:34:42.852766Z","end":"2026-07-23T10:34:43.163925Z","steps":["trace[59800609] 'process raft request' (duration: 311.037375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:34:43.164085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:34:42.852753Z","time spent":"311.25995ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:34:43.635522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.052439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T10:34:43.63566Z","caller":"traceutil/trace.go:171","msg":"trace[1895378919] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1154; }","duration":"280.059605ms","start":"2026-07-23T10:34:43.355579Z","end":"2026-07-23T10:34:43.635638Z","steps":["trace[1895378919] 'read index received' (duration: 77.669736ms)","trace[1895378919] 'applied index is now lower than readState.Index' (duration: 202.388149ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:34:43.635709Z","caller":"traceutil/trace.go:171","msg":"trace[1321426579] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"460.093007ms","start":"2026-07-23T10:34:43.175574Z","end":"2026-07-23T10:34:43.635667Z","steps":["trace[1321426579] 'process raft request' (duration: 257.781281ms)","trace[1321426579] 'compare' (duration: 201.933326ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:34:43.635792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.211788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T10:34:43.63584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:34:43.175562Z","time spent":"460.20888ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:34:43.635865Z","caller":"traceutil/trace.go:171","msg":"trace[561492576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"280.300642ms","start":"2026-07-23T10:34:43.355532Z","end":"2026-07-23T10:34:43.635833Z","steps":["trace[561492576] 'agreement among raft nodes before linearized reading' (duration: 280.216779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:34:43.642335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.184348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-23T10:34:43.642389Z","caller":"traceutil/trace.go:171","msg":"trace[503859754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1115; }","duration":"155.284591ms","start":"2026-07-23T10:34:43.487095Z","end":"2026-07-23T10:34:43.642379Z","steps":["trace[503859754] 'agreement among raft nodes before linearized reading' (duration: 155.135157ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:34:43.642352Z","caller":"traceutil/trace.go:171","msg":"trace[772405868] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"178.650144ms","start":"2026-07-23T10:34:43.463681Z","end":"2026-07-23T10:34:43.642332Z","steps":["trace[772405868] 'process raft request' (duration: 178.377616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:34:43.642409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.316743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T10:34:43.642511Z","caller":"traceutil/trace.go:171","msg":"trace[1972222803] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1115; }","duration":"135.477018ms","start":"2026-07-23T10:34:43.507013Z","end":"2026-07-23T10:34:43.64249Z","steps":["trace[1972222803] 'agreement among raft nodes before linearized reading' (duration: 135.266082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:34:43.642573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.389785ms","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-07-23T10:34:43.642614Z","caller":"traceutil/trace.go:171","msg":"trace[1917447606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1115; }","duration":"147.463158ms","start":"2026-07-23T10:34:43.495144Z","end":"2026-07-23T10:34:43.642607Z","steps":["trace[1917447606] 'agreement among raft nodes before linearized reading' (duration: 147.382067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:34:43.642538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.685913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-23T10:34:43.642727Z","caller":"traceutil/trace.go:171","msg":"trace[1423698682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1115; }","duration":"155.909807ms","start":"2026-07-23T10:34:43.486803Z","end":"2026-07-23T10:34:43.642712Z","steps":["trace[1423698682] 'agreement among raft nodes before linearized reading' (duration: 155.63619ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:34:45.387736Z","caller":"traceutil/trace.go:171","msg":"trace[630044021] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"215.527933ms","start":"2026-07-23T10:34:45.172178Z","end":"2026-07-23T10:34:45.387706Z","steps":["trace[630044021] 'process raft request' (duration: 215.264005ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:01.799644Z","caller":"traceutil/trace.go:171","msg":"trace[648951987] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"319.425413ms","start":"2026-07-23T10:35:01.480163Z","end":"2026-07-23T10:35:01.799588Z","steps":["trace[648951987] 'process raft request' (duration: 319.152454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:01.799913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:01.480138Z","time spent":"319.658059ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:35:02.314078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.136447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T10:35:02.314221Z","caller":"traceutil/trace.go:171","msg":"trace[139889016] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"505.229079ms","start":"2026-07-23T10:35:01.808969Z","end":"2026-07-23T10:35:02.314198Z","steps":["trace[139889016] 'process raft request' (duration: 149.858967ms)","trace[139889016] 'compare' (duration: 354.989792ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:35:02.314289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:01.808933Z","time spent":"505.322432ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:35:02.332644Z","caller":"traceutil/trace.go:171","msg":"trace[2065724581] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1271; }","duration":"502.584895ms","start":"2026-07-23T10:35:01.83002Z","end":"2026-07-23T10:35:02.332605Z","steps":["trace[2065724581] 'read index received' (duration: 128.83488ms)","trace[2065724581] 'applied index is now lower than readState.Index' (duration: 373.748725ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:35:02.332705Z","caller":"traceutil/trace.go:171","msg":"trace[662694542] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"523.53455ms","start":"2026-07-23T10:35:01.809123Z","end":"2026-07-23T10:35:02.332657Z","steps":["trace[662694542] 'process raft request' (duration: 523.307644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:02.333022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.56026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T10:35:02.333094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.794056ms","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-07-23T10:35:02.333126Z","caller":"traceutil/trace.go:171","msg":"trace[1674402663] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1226; }","duration":"479.711254ms","start":"2026-07-23T10:35:01.853394Z","end":"2026-07-23T10:35:02.333105Z","steps":["trace[1674402663] 'agreement among raft nodes before linearized reading' (duration: 479.497638ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:02.333149Z","caller":"traceutil/trace.go:171","msg":"trace[1820097338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1226; }","duration":"498.91854ms","start":"2026-07-23T10:35:01.834218Z","end":"2026-07-23T10:35:02.333136Z","steps":["trace[1820097338] 'agreement among raft nodes before linearized reading' (duration: 498.683874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:02.333047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.007457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-23T10:35:02.33324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:01.834207Z","time spent":"498.983113ms","remote":"127.0.0.1:41646","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-07-23T10:35:02.33326Z","caller":"traceutil/trace.go:171","msg":"trace[375435584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1226; }","duration":"503.228233ms","start":"2026-07-23T10:35:01.829963Z","end":"2026-07-23T10:35:02.333191Z","steps":["trace[375435584] 'agreement among raft nodes before linearized reading' (duration: 502.781651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:02.333083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:01.809111Z","time spent":"523.897771ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:35:02.333314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:01.829937Z","time spent":"503.363066ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T10:35:02.333941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.687194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-23T10:35:02.334026Z","caller":"traceutil/trace.go:171","msg":"trace[908613186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1226; }","duration":"498.789056ms","start":"2026-07-23T10:35:01.835226Z","end":"2026-07-23T10:35:02.334015Z","steps":["trace[908613186] 'agreement among raft nodes before linearized reading' (duration: 497.956383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:02.334112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:01.835218Z","time spent":"498.884928ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T10:35:02.333181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:01.853349Z","time spent":"479.821088ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-23T10:35:14.931893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.166259ms","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-07-23T10:35:14.932025Z","caller":"traceutil/trace.go:171","msg":"trace[1015230508] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1268; }","duration":"279.319424ms","start":"2026-07-23T10:35:14.652687Z","end":"2026-07-23T10:35:14.932006Z","steps":["trace[1015230508] 'range keys from in-memory index tree' (duration: 279.135099ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:14.932047Z","caller":"traceutil/trace.go:171","msg":"trace[486620671] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"322.187491ms","start":"2026-07-23T10:35:14.609837Z","end":"2026-07-23T10:35:14.932025Z","steps":["trace[486620671] 'process raft request' (duration: 320.296707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:14.932194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:14.609817Z","time spent":"322.322135ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:35:18.115544Z","caller":"traceutil/trace.go:171","msg":"trace[1629005403] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"286.63444ms","start":"2026-07-23T10:35:17.828865Z","end":"2026-07-23T10:35:18.1155Z","steps":["trace[1629005403] 'process raft request' (duration: 286.450325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:18.115723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.33431ms","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-07-23T10:35:18.115789Z","caller":"traceutil/trace.go:171","msg":"trace[338621427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"125.468104ms","start":"2026-07-23T10:35:17.990307Z","end":"2026-07-23T10:35:18.115775Z","steps":["trace[338621427] 'agreement among raft nodes before linearized reading' (duration: 125.268168ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:18.115528Z","caller":"traceutil/trace.go:171","msg":"trace[2116926634] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"125.105924ms","start":"2026-07-23T10:35:17.990366Z","end":"2026-07-23T10:35:18.115471Z","steps":["trace[2116926634] 'read index received' (duration: 124.932009ms)","trace[2116926634] 'applied index is now lower than readState.Index' (duration: 172.575µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:35:18.239425Z","caller":"traceutil/trace.go:171","msg":"trace[1556002657] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"111.24654ms","start":"2026-07-23T10:35:18.128155Z","end":"2026-07-23T10:35:18.239402Z","steps":["trace[1556002657] 'process raft request' (duration: 111.202578ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:18.240104Z","caller":"traceutil/trace.go:171","msg":"trace[604095599] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"112.121955ms","start":"2026-07-23T10:35:18.127953Z","end":"2026-07-23T10:35:18.240075Z","steps":["trace[604095599] 'process raft request' (duration: 111.342113ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:18.240403Z","caller":"traceutil/trace.go:171","msg":"trace[564384212] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"112.487336ms","start":"2026-07-23T10:35:18.1279Z","end":"2026-07-23T10:35:18.240387Z","steps":["trace[564384212] 'process raft request' (duration: 111.26316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:18.629447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.743873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T10:35:18.629544Z","caller":"traceutil/trace.go:171","msg":"trace[1398827069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1296; }","duration":"274.897497ms","start":"2026-07-23T10:35:18.354629Z","end":"2026-07-23T10:35:18.629527Z","steps":["trace[1398827069] 'range keys from in-memory index tree' (duration: 274.668112ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:18.630237Z","caller":"traceutil/trace.go:171","msg":"trace[1675812016] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"247.831539ms","start":"2026-07-23T10:35:18.382391Z","end":"2026-07-23T10:35:18.630223Z","steps":["trace[1675812016] 'process raft request' (duration: 215.294744ms)","trace[1675812016] 'compare' (duration: 31.659719ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:35:18.630498Z","caller":"traceutil/trace.go:171","msg":"trace[498698709] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"143.787224ms","start":"2026-07-23T10:35:18.486271Z","end":"2026-07-23T10:35:18.630059Z","steps":["trace[498698709] 'read index received' (duration: 111.360103ms)","trace[498698709] 'applied index is now lower than readState.Index' (duration: 32.426071ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:35:18.630638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.35968ms","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-07-23T10:35:18.631056Z","caller":"traceutil/trace.go:171","msg":"trace[1960963931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1297; }","duration":"144.777722ms","start":"2026-07-23T10:35:18.486266Z","end":"2026-07-23T10:35:18.631044Z","steps":["trace[1960963931] 'agreement among raft nodes before linearized reading' (duration: 144.282578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:18.631084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.907557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-23T10:35:18.631185Z","caller":"traceutil/trace.go:171","msg":"trace[1148568926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1297; }","duration":"106.036551ms","start":"2026-07-23T10:35:18.525126Z","end":"2026-07-23T10:35:18.631162Z","steps":["trace[1148568926] 'agreement among raft nodes before linearized reading' (duration: 105.792794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:18.630823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.917345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-23T10:35:18.63126Z","caller":"traceutil/trace.go:171","msg":"trace[1306646558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1297; }","duration":"108.384698ms","start":"2026-07-23T10:35:18.522861Z","end":"2026-07-23T10:35:18.631246Z","steps":["trace[1306646558] 'agreement among raft nodes before linearized reading' (duration: 107.902734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:18.630961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.154115ms","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-07-23T10:35:18.631323Z","caller":"traceutil/trace.go:171","msg":"trace[1980940667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1297; }","duration":"100.542406ms","start":"2026-07-23T10:35:18.530771Z","end":"2026-07-23T10:35:18.631313Z","steps":["trace[1980940667] 'agreement among raft nodes before linearized reading' (duration: 100.069942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:19.370821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.127938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T10:35:19.370906Z","caller":"traceutil/trace.go:171","msg":"trace[1778011912] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"734.056328ms","start":"2026-07-23T10:35:18.636836Z","end":"2026-07-23T10:35:19.370892Z","steps":["trace[1778011912] 'process raft request' (duration: 523.796107ms)","trace[1778011912] 'compare' (duration: 209.059777ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:35:19.370948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:18.636816Z","time spent":"734.11254ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:35:19.426725Z","caller":"traceutil/trace.go:171","msg":"trace[1029795344] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"789.117162ms","start":"2026-07-23T10:35:18.637592Z","end":"2026-07-23T10:35:19.426709Z","steps":["trace[1029795344] 'process raft request' (duration: 789.077111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:19.426833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:18.637583Z","time spent":"789.194015ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:35:19.426795Z","caller":"traceutil/trace.go:171","msg":"trace[177173327] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"789.247487ms","start":"2026-07-23T10:35:18.637506Z","end":"2026-07-23T10:35:19.426753Z","steps":["trace[177173327] 'process raft request' (duration: 789.076602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:19.426928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.677374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T10:35:19.426958Z","caller":"traceutil/trace.go:171","msg":"trace[46844958] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1301; }","duration":"729.745487ms","start":"2026-07-23T10:35:18.697204Z","end":"2026-07-23T10:35:19.42695Z","steps":["trace[46844958] 'agreement among raft nodes before linearized reading' (duration: 729.675924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:19.42698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:18.697189Z","time spent":"729.784978ms","remote":"127.0.0.1:41848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-23T10:35:19.426982Z","caller":"traceutil/trace.go:171","msg":"trace[1318578608] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"789.320779ms","start":"2026-07-23T10:35:18.637639Z","end":"2026-07-23T10:35:19.42696Z","steps":["trace[1318578608] 'process raft request' (duration: 789.047141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:19.427016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:18.637484Z","time spent":"789.416131ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:35:19.426851Z","caller":"traceutil/trace.go:171","msg":"trace[745323115] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1347; }","duration":"729.595132ms","start":"2026-07-23T10:35:18.697242Z","end":"2026-07-23T10:35:19.426837Z","steps":["trace[745323115] 'read index received' (duration: 463.33963ms)","trace[745323115] 'applied index is now lower than readState.Index' (duration: 266.254272ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:35:19.427091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:18.637631Z","time spent":"789.40228ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:35:20.361193Z","caller":"traceutil/trace.go:171","msg":"trace[2078343978] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"243.027002ms","start":"2026-07-23T10:35:20.118143Z","end":"2026-07-23T10:35:20.36117Z","steps":["trace[2078343978] 'process raft request' (duration: 242.894008ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:20.36118Z","caller":"traceutil/trace.go:171","msg":"trace[613803758] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"212.780094ms","start":"2026-07-23T10:35:20.148378Z","end":"2026-07-23T10:35:20.361158Z","steps":["trace[613803758] 'read index received' (duration: 212.613139ms)","trace[613803758] 'applied index is now lower than readState.Index' (duration: 165.955µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:35:20.361392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.00153ms","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-07-23T10:35:20.361428Z","caller":"traceutil/trace.go:171","msg":"trace[1657834971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1303; }","duration":"213.080833ms","start":"2026-07-23T10:35:20.148338Z","end":"2026-07-23T10:35:20.361419Z","steps":["trace[1657834971] 'agreement among raft nodes before linearized reading' (duration: 212.886937ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:21.336578Z","caller":"traceutil/trace.go:171","msg":"trace[677270027] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"970.594788ms","start":"2026-07-23T10:35:20.365952Z","end":"2026-07-23T10:35:21.336547Z","steps":["trace[677270027] 'process raft request' (duration: 970.462654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:21.336734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:20.365929Z","time spent":"970.731322ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:35:21.500389Z","caller":"traceutil/trace.go:171","msg":"trace[1642760372] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1355; }","duration":"145.161359ms","start":"2026-07-23T10:35:21.355208Z","end":"2026-07-23T10:35:21.500369Z","steps":["trace[1642760372] 'read index received' (duration: 119.290215ms)","trace[1642760372] 'applied index is now lower than readState.Index' (duration: 25.870284ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:35:21.500507Z","caller":"traceutil/trace.go:171","msg":"trace[494991058] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"864.949772ms","start":"2026-07-23T10:35:20.635531Z","end":"2026-07-23T10:35:21.500481Z","steps":["trace[494991058] 'process raft request' (duration: 839.093109ms)","trace[494991058] 'compare' (duration: 25.35599ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:35:21.500516Z","caller":"traceutil/trace.go:171","msg":"trace[923130307] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"337.903278ms","start":"2026-07-23T10:35:21.16256Z","end":"2026-07-23T10:35:21.500463Z","steps":["trace[923130307] 'process raft request' (duration: 337.729574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:21.500626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:20.635511Z","time spent":"865.047376ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:35:21.500644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.955556ms","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-07-23T10:35:21.500525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.303003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T10:35:21.500717Z","caller":"traceutil/trace.go:171","msg":"trace[56021446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1306; }","duration":"145.54215ms","start":"2026-07-23T10:35:21.355168Z","end":"2026-07-23T10:35:21.50071Z","steps":["trace[56021446] 'agreement among raft nodes before linearized reading' (duration: 145.314003ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:21.500711Z","caller":"traceutil/trace.go:171","msg":"trace[92297257] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1306; }","duration":"125.062168ms","start":"2026-07-23T10:35:21.375618Z","end":"2026-07-23T10:35:21.500681Z","steps":["trace[92297257] 'agreement among raft nodes before linearized reading' (duration: 124.901814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:21.500722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:21.162541Z","time spent":"338.068363ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:35:21.966673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.462571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T10:35:21.966847Z","caller":"traceutil/trace.go:171","msg":"trace[44847849] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"461.32517ms","start":"2026-07-23T10:35:21.505496Z","end":"2026-07-23T10:35:21.966821Z","steps":["trace[44847849] 'process raft request' (duration: 96.590021ms)","trace[44847849] 'compare' (duration: 364.236685ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:35:21.966921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:21.505477Z","time spent":"461.413142ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:35:22.461373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.996501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T10:35:22.461647Z","caller":"traceutil/trace.go:171","msg":"trace[204461] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"955.908174ms","start":"2026-07-23T10:35:21.505716Z","end":"2026-07-23T10:35:22.461624Z","steps":["trace[204461] 'process raft request' (duration: 955.853242ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:22.461714Z","caller":"traceutil/trace.go:171","msg":"trace[2077845203] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"956.13424ms","start":"2026-07-23T10:35:21.50554Z","end":"2026-07-23T10:35:22.461674Z","steps":["trace[2077845203] 'process raft request' (duration: 808.751359ms)","trace[2077845203] 'compare' (duration: 146.888978ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:35:22.461804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:21.505709Z","time spent":"956.044228ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:35:22.46186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:21.505529Z","time spent":"956.254824ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:35:22.461714Z","caller":"traceutil/trace.go:171","msg":"trace[419414895] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"956.10169ms","start":"2026-07-23T10:35:21.505589Z","end":"2026-07-23T10:35:22.461691Z","steps":["trace[419414895] 'process raft request' (duration: 955.905304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:22.462006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:21.50556Z","time spent":"956.378658ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T10:35:23.843325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.057303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T10:35:23.843427Z","caller":"traceutil/trace.go:171","msg":"trace[247647424] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"336.380766ms","start":"2026-07-23T10:35:23.507029Z","end":"2026-07-23T10:35:23.843409Z","steps":["trace[247647424] 'process raft request' (duration: 91.166328ms)","trace[247647424] 'compare' (duration: 244.95321ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:35:23.843675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:23.507Z","time spent":"336.458197ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:35:28.606623Z","caller":"traceutil/trace.go:171","msg":"trace[151006076] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"101.409527ms","start":"2026-07-23T10:35:28.505189Z","end":"2026-07-23T10:35:28.606599Z","steps":["trace[151006076] 'process raft request' (duration: 100.551993ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:33.505785Z","caller":"traceutil/trace.go:171","msg":"trace[2094917977] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"127.22767ms","start":"2026-07-23T10:35:33.378531Z","end":"2026-07-23T10:35:33.505759Z","steps":["trace[2094917977] 'process raft request' (duration: 127.040725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:34.597496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11554723228501077948,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T10:35:34.748889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103637815s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T10:35:34.748998Z","caller":"traceutil/trace.go:171","msg":"trace[1393943441] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"1.23909922s","start":"2026-07-23T10:35:33.509882Z","end":"2026-07-23T10:35:34.748981Z","steps":["trace[1393943441] 'process raft request' (duration: 135.355811ms)","trace[1393943441] 'compare' (duration: 1.103408849s)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:35:34.749044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:33.509858Z","time spent":"1.239163112s","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:35:34.767179Z","caller":"traceutil/trace.go:171","msg":"trace[218465733] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"859.790137ms","start":"2026-07-23T10:35:33.907362Z","end":"2026-07-23T10:35:34.767152Z","steps":["trace[218465733] 'process raft request' (duration: 859.630002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:34.767508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:33.90733Z","time spent":"860.000592ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T10:35:34.7724Z","caller":"traceutil/trace.go:171","msg":"trace[1478404531] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1442; }","duration":"675.889778ms","start":"2026-07-23T10:35:34.096436Z","end":"2026-07-23T10:35:34.772326Z","steps":["trace[1478404531] 'read index received' (duration: 670.631579ms)","trace[1478404531] 'applied index is now lower than readState.Index' (duration: 5.254689ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T10:35:34.772785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.334662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-23T10:35:34.776256Z","caller":"traceutil/trace.go:171","msg":"trace[815612561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1390; }","duration":"679.831641ms","start":"2026-07-23T10:35:34.096384Z","end":"2026-07-23T10:35:34.776215Z","steps":["trace[815612561] 'agreement among raft nodes before linearized reading' (duration: 676.224378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:34.776704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:34.096362Z","time spent":"679.954305ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T10:35:34.778179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.377445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-07-23T10:35:34.778221Z","caller":"traceutil/trace.go:171","msg":"trace[556484391] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1390; }","duration":"594.444837ms","start":"2026-07-23T10:35:34.183765Z","end":"2026-07-23T10:35:34.77821Z","steps":["trace[556484391] 'agreement among raft nodes before linearized reading' (duration: 593.952933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:34.778255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:34.183747Z","time spent":"594.501469ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-23T10:35:34.778432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.727626ms","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-07-23T10:35:34.778511Z","caller":"traceutil/trace.go:171","msg":"trace[291913010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1390; }","duration":"144.82012ms","start":"2026-07-23T10:35:34.633659Z","end":"2026-07-23T10:35:34.778479Z","steps":["trace[291913010] 'agreement among raft nodes before linearized reading' (duration: 144.656305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:34.778593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.315513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-23T10:35:34.77859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.034612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T10:35:34.778579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.582511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-23T10:35:34.778647Z","caller":"traceutil/trace.go:171","msg":"trace[150724975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1390; }","duration":"145.372056ms","start":"2026-07-23T10:35:34.63325Z","end":"2026-07-23T10:35:34.778622Z","steps":["trace[150724975] 'agreement among raft nodes before linearized reading' (duration: 145.275163ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:34.778658Z","caller":"traceutil/trace.go:171","msg":"trace[1078904815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1390; }","duration":"424.126235ms","start":"2026-07-23T10:35:34.354507Z","end":"2026-07-23T10:35:34.778633Z","steps":["trace[1078904815] 'agreement among raft nodes before linearized reading' (duration: 424.025302ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:34.778682Z","caller":"traceutil/trace.go:171","msg":"trace[544456058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1390; }","duration":"145.762046ms","start":"2026-07-23T10:35:34.632898Z","end":"2026-07-23T10:35:34.77866Z","steps":["trace[544456058] 'agreement among raft nodes before linearized reading' (duration: 145.504329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:35:34.778859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.788605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-23T10:35:34.778799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T10:35:34.354491Z","time spent":"424.28364ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-23T10:35:34.779121Z","caller":"traceutil/trace.go:171","msg":"trace[285691699] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1390; }","duration":"141.185077ms","start":"2026-07-23T10:35:34.637911Z","end":"2026-07-23T10:35:34.779096Z","steps":["trace[285691699] 'agreement among raft nodes before linearized reading' (duration: 140.61689ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:35:38.878572Z","caller":"traceutil/trace.go:171","msg":"trace[45607282] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"100.79717ms","start":"2026-07-23T10:35:38.77775Z","end":"2026-07-23T10:35:38.878547Z","steps":["trace[45607282] 'process raft request' (duration: 100.648986ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:37:07.751568Z","caller":"traceutil/trace.go:171","msg":"trace[1693236852] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"229.836059ms","start":"2026-07-23T10:37:07.52169Z","end":"2026-07-23T10:37:07.751526Z","steps":["trace[1693236852] 'process raft request' (duration: 206.501406ms)","trace[1693236852] 'compare' (duration: 23.110667ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:37:07.751967Z","caller":"traceutil/trace.go:171","msg":"trace[1285365591] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1888; }","duration":"204.643723ms","start":"2026-07-23T10:37:07.547284Z","end":"2026-07-23T10:37:07.751928Z","steps":["trace[1285365591] 'read index received' (duration: 20.371398ms)","trace[1285365591] 'applied index is now lower than readState.Index' (duration: 184.269385ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T10:37:07.752238Z","caller":"traceutil/trace.go:171","msg":"trace[463890777] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"230.104086ms","start":"2026-07-23T10:37:07.52211Z","end":"2026-07-23T10:37:07.752214Z","steps":["trace[463890777] 'process raft request' (duration: 229.366636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T10:37:07.752341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.036195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-23T10:37:07.75241Z","caller":"traceutil/trace.go:171","msg":"trace[1767378084] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1819; }","duration":"205.176489ms","start":"2026-07-23T10:37:07.547212Z","end":"2026-07-23T10:37:07.752389Z","steps":["trace[1767378084] 'agreement among raft nodes before linearized reading' (duration: 204.863ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:39:28.706443Z","caller":"traceutil/trace.go:171","msg":"trace[710202000] transaction","detail":"{read_only:false; response_revision:2464; number_of_response:1; }","duration":"169.059818ms","start":"2026-07-23T10:39:28.537144Z","end":"2026-07-23T10:39:28.706204Z","steps":["trace[710202000] 'process raft request' (duration: 168.872233ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:39:28.711336Z","caller":"traceutil/trace.go:171","msg":"trace[857860452] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"174.027828ms","start":"2026-07-23T10:39:28.537296Z","end":"2026-07-23T10:39:28.711323Z","steps":["trace[857860452] 'process raft request' (duration: 173.963506ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T10:39:52.784602Z","caller":"traceutil/trace.go:171","msg":"trace[1666951856] transaction","detail":"{read_only:false; response_revision:2595; number_of_response:1; }","duration":"128.191571ms","start":"2026-07-23T10:39:52.656381Z","end":"2026-07-23T10:39:52.784572Z","steps":["trace[1666951856] 'process raft request' (duration: 127.925823ms)"],"step_count":1}