{"level":"warn","ts":"2026-05-05T13:45:14.923077Z","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-05T13:45:14.923186Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.35: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.35:2380","--initial-cluster=instance=https://199.19.213.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.35: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-05T13:45:14.923261Z","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-05T13:45:14.923276Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.35:2380"]} {"level":"info","ts":"2026-05-05T13:45:14.923299Z","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-05T13:45:14.923957Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.35:2379"]} {"level":"info","ts":"2026-05-05T13:45:14.924055Z","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.35:2380"],"listen-peer-urls":["https://199.19.213.35:2380"],"advertise-client-urls":["https://199.19.213.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.35: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-05T13:45:14.929613Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.34518ms"} {"level":"info","ts":"2026-05-05T13:45:14.93831Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d1606b245347dc08","cluster-id":"e68e205b85faf98a"} {"level":"info","ts":"2026-05-05T13:45:14.93839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T13:45:14.93841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became follower at term 0"} {"level":"info","ts":"2026-05-05T13:45:14.93842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d1606b245347dc08 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T13:45:14.938432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became follower at term 1"} {"level":"info","ts":"2026-05-05T13:45:14.938473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 switched to configuration voters=(15087176555451374600)"} {"level":"warn","ts":"2026-05-05T13:45:14.944181Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T13:45:14.94613Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T13:45:14.94779Z","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-05T13:45:14.949756Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d1606b245347dc08","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T13:45:14.949853Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d1606b245347dc08","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T13:45:14.949924Z","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-05T13:45:14.950214Z","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-05T13:45:14.950271Z","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-05T13:45:14.952114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 switched to configuration voters=(15087176555451374600)"} {"level":"info","ts":"2026-05-05T13:45:14.952324Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e68e205b85faf98a","local-member-id":"d1606b245347dc08","added-peer-id":"d1606b245347dc08","added-peer-peer-urls":["https://199.19.213.35:2380"]} {"level":"info","ts":"2026-05-05T13:45:14.954423Z","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-05T13:45:14.95458Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.35:2380"} {"level":"info","ts":"2026-05-05T13:45:14.954623Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.35:2380"} {"level":"info","ts":"2026-05-05T13:45:14.954973Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d1606b245347dc08","initial-advertise-peer-urls":["https://199.19.213.35:2380"],"listen-peer-urls":["https://199.19.213.35:2380"],"advertise-client-urls":["https://199.19.213.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T13:45:14.955033Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T13:45:15.339018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T13:45:15.339087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T13:45:15.339132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 received MsgPreVoteResp from d1606b245347dc08 at term 1"} {"level":"info","ts":"2026-05-05T13:45:15.339154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became candidate at term 2"} {"level":"info","ts":"2026-05-05T13:45:15.339166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 received MsgVoteResp from d1606b245347dc08 at term 2"} {"level":"info","ts":"2026-05-05T13:45:15.339184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became leader at term 2"} {"level":"info","ts":"2026-05-05T13:45:15.339214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d1606b245347dc08 elected leader d1606b245347dc08 at term 2"} {"level":"info","ts":"2026-05-05T13:45:15.340144Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:45:15.340934Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d1606b245347dc08","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.35:2379]}","request-path":"/0/members/d1606b245347dc08/attributes","cluster-id":"e68e205b85faf98a","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T13:45:15.340945Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T13:45:15.340999Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T13:45:15.341354Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e68e205b85faf98a","local-member-id":"d1606b245347dc08","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:45:15.341491Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:45:15.341535Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:45:15.341835Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T13:45:15.341882Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T13:45:15.344525Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.35:2379"} {"level":"info","ts":"2026-05-05T13:45:15.347646Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-05T13:45:18.442993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.733671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-05T13:45:18.44315Z","caller":"traceutil/trace.go:171","msg":"trace[877901744] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"414.930496ms","start":"2026-05-05T13:45:18.028196Z","end":"2026-05-05T13:45:18.443127Z","steps":["trace[877901744] 'process raft request' (duration: 296.30361ms)","trace[877901744] 'compare' (duration: 117.583278ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:45:18.443241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:18.028181Z","time spent":"415.023489ms","remote":"127.0.0.1:38350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":767,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:45:19.271279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15855096178292144962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T13:45:19.771984Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15855096178292144962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T13:45:20.109182Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.659523407s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T13:45:20.109554Z","caller":"traceutil/trace.go:171","msg":"trace[1810278369] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"1.659962539s","start":"2026-05-05T13:45:18.449564Z","end":"2026-05-05T13:45:20.109526Z","steps":["trace[1810278369] 'process raft request' (duration: 1.659836206s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:20.1097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:18.449541Z","time spent":"1.660091902s","remote":"127.0.0.1:38350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":710,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:45:20.173234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:18.831422Z","time spent":"1.341809088s","remote":"127.0.0.1:38116","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-05T13:45:20.173468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402488381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T13:45:20.173538Z","caller":"traceutil/trace.go:171","msg":"trace[1292281559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:121; }","duration":"1.402639145s","start":"2026-05-05T13:45:18.770882Z","end":"2026-05-05T13:45:20.173521Z","steps":["trace[1292281559] 'agreement among raft nodes before linearized reading' (duration: 1.40244616s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:20.173573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:18.770862Z","time spent":"1.402705227s","remote":"127.0.0.1:37994","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-05-05T13:45:20.173185Z","caller":"traceutil/trace.go:171","msg":"trace[554097445] linearizableReadLoop","detail":"{readStateIndex:126; appliedIndex:124; }","duration":"1.402238955s","start":"2026-05-05T13:45:18.770922Z","end":"2026-05-05T13:45:20.173161Z","steps":["trace[554097445] 'read index received' (duration: 1.338451321s)","trace[554097445] 'applied index is now lower than readState.Index' (duration: 63.786414ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:45:20.675174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15855096178292144967,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T13:45:20.817464Z","caller":"traceutil/trace.go:171","msg":"trace[763651445] linearizableReadLoop","detail":"{readStateIndex:128; appliedIndex:126; }","duration":"642.563254ms","start":"2026-05-05T13:45:20.174873Z","end":"2026-05-05T13:45:20.817436Z","steps":["trace[763651445] 'read index received' (duration: 635.51284ms)","trace[763651445] 'applied index is now lower than readState.Index' (duration: 7.049544ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:45:20.817555Z","caller":"traceutil/trace.go:171","msg":"trace[1775098827] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"639.71508ms","start":"2026-05-05T13:45:20.177834Z","end":"2026-05-05T13:45:20.81755Z","steps":["trace[1775098827] 'process raft request' (duration: 639.569036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:20.817618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:20.177811Z","time spent":"639.762312ms","remote":"127.0.0.1:38350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T13:45:20.817619Z","caller":"traceutil/trace.go:171","msg":"trace[1456861095] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"642.826811ms","start":"2026-05-05T13:45:20.174764Z","end":"2026-05-05T13:45:20.817591Z","steps":["trace[1456861095] 'process raft request' (duration: 635.680415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:20.817805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.926554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T13:45:20.817813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:20.17474Z","time spent":"643.012046ms","remote":"127.0.0.1:38116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T13:45:20.81783Z","caller":"traceutil/trace.go:171","msg":"trace[1657770715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:123; }","duration":"642.996076ms","start":"2026-05-05T13:45:20.174826Z","end":"2026-05-05T13:45:20.817822Z","steps":["trace[1657770715] 'agreement among raft nodes before linearized reading' (duration: 642.922043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:20.817854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:20.174788Z","time spent":"643.062037ms","remote":"127.0.0.1:38008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T13:45:20.81795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.69315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T13:45:20.817969Z","caller":"traceutil/trace.go:171","msg":"trace[1768841810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:123; }","duration":"217.743501ms","start":"2026-05-05T13:45:20.600218Z","end":"2026-05-05T13:45:20.817961Z","steps":["trace[1768841810] 'agreement among raft nodes before linearized reading' (duration: 217.718341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:20.818099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.57363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T13:45:20.818539Z","caller":"traceutil/trace.go:171","msg":"trace[1343743040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:123; }","duration":"143.041252ms","start":"2026-05-05T13:45:20.675491Z","end":"2026-05-05T13:45:20.818532Z","steps":["trace[1343743040] 'agreement among raft nodes before linearized reading' (duration: 142.59405ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:20.98479Z","caller":"traceutil/trace.go:171","msg":"trace[1976912022] transaction","detail":"{read_only:false; response_revision:124; number_of_response:1; }","duration":"163.167577ms","start":"2026-05-05T13:45:20.821592Z","end":"2026-05-05T13:45:20.98476Z","steps":["trace[1976912022] 'process raft request' (duration: 158.745912ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:20.987416Z","caller":"traceutil/trace.go:171","msg":"trace[1035227038] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"162.968422ms","start":"2026-05-05T13:45:20.824434Z","end":"2026-05-05T13:45:20.987403Z","steps":["trace[1035227038] 'process raft request' (duration: 162.935781ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:20.987705Z","caller":"traceutil/trace.go:171","msg":"trace[1409275559] transaction","detail":"{read_only:false; response_revision:125; number_of_response:1; }","duration":"164.81553ms","start":"2026-05-05T13:45:20.822863Z","end":"2026-05-05T13:45:20.987679Z","steps":["trace[1409275559] 'process raft request' (duration: 164.394889ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:20.98775Z","caller":"traceutil/trace.go:171","msg":"trace[1676952335] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"163.627399ms","start":"2026-05-05T13:45:20.824019Z","end":"2026-05-05T13:45:20.987646Z","steps":["trace[1676952335] 'process raft request' (duration: 163.313271ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:48.141766Z","caller":"traceutil/trace.go:171","msg":"trace[920379609] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"634.68349ms","start":"2026-05-05T13:45:47.507058Z","end":"2026-05-05T13:45:48.141741Z","steps":["trace[920379609] 'process raft request' (duration: 634.509475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:48.141898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:47.507037Z","time spent":"634.803912ms","remote":"127.0.0.1:38082","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-05T13:45:48.150923Z","caller":"traceutil/trace.go:171","msg":"trace[1733274704] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"627.745018ms","start":"2026-05-05T13:45:47.523155Z","end":"2026-05-05T13:45:48.1509Z","steps":["trace[1733274704] 'process raft request' (duration: 627.49996ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:48.151011Z","caller":"traceutil/trace.go:171","msg":"trace[2057727680] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:532; }","duration":"292.993005ms","start":"2026-05-05T13:45:47.858002Z","end":"2026-05-05T13:45:48.150995Z","steps":["trace[2057727680] 'read index received' (duration: 284.078922ms)","trace[2057727680] 'applied index is now lower than readState.Index' (duration: 8.913393ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:45:48.151071Z","caller":"traceutil/trace.go:171","msg":"trace[2127577955] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"642.739379ms","start":"2026-05-05T13:45:47.508325Z","end":"2026-05-05T13:45:48.151064Z","steps":["trace[2127577955] 'process raft request' (duration: 642.057151ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:48.151094Z","caller":"traceutil/trace.go:171","msg":"trace[212135115] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"619.296518ms","start":"2026-05-05T13:45:47.531791Z","end":"2026-05-05T13:45:48.151087Z","steps":["trace[212135115] 'process raft request' (duration: 618.888967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:48.151106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:47.508308Z","time spent":"642.78536ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:45:48.151163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:47.531762Z","time spent":"619.351439ms","remote":"127.0.0.1:38082","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-05T13:45:48.151208Z","caller":"traceutil/trace.go:171","msg":"trace[357433232] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"637.113622ms","start":"2026-05-05T13:45:47.514084Z","end":"2026-05-05T13:45:48.151198Z","steps":["trace[357433232] 'process raft request' (duration: 636.381503ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:48.151251Z","caller":"traceutil/trace.go:171","msg":"trace[163776870] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"588.442962ms","start":"2026-05-05T13:45:47.562801Z","end":"2026-05-05T13:45:48.151244Z","steps":["trace[163776870] 'process raft request' (duration: 587.910498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:48.151257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:47.514066Z","time spent":"637.171424ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:45:48.151284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:47.562782Z","time spent":"588.487463ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T13:45:48.151324Z","caller":"traceutil/trace.go:171","msg":"trace[1753942689] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"563.552963ms","start":"2026-05-05T13:45:47.587766Z","end":"2026-05-05T13:45:48.151319Z","steps":["trace[1753942689] 'process raft request' (duration: 562.989358ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:48.151365Z","caller":"traceutil/trace.go:171","msg":"trace[2028296076] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"633.2293ms","start":"2026-05-05T13:45:47.518126Z","end":"2026-05-05T13:45:48.151356Z","steps":["trace[2028296076] 'process raft request' (duration: 632.404038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:48.151366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:47.587748Z","time spent":"563.594084ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:45:48.151409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:47.518116Z","time spent":"633.270922ms","remote":"127.0.0.1:38082","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-05T13:45:48.151442Z","caller":"traceutil/trace.go:171","msg":"trace[1874834136] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"447.748062ms","start":"2026-05-05T13:45:47.703683Z","end":"2026-05-05T13:45:48.151431Z","steps":["trace[1874834136] 'process raft request' (duration: 447.243589ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:48.151448Z","caller":"traceutil/trace.go:171","msg":"trace[378153693] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"539.089015ms","start":"2026-05-05T13:45:47.61234Z","end":"2026-05-05T13:45:48.151429Z","steps":["trace[378153693] 'process raft request' (duration: 538.523021ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:48.151482Z","caller":"traceutil/trace.go:171","msg":"trace[1641717583] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"630.342055ms","start":"2026-05-05T13:45:47.521132Z","end":"2026-05-05T13:45:48.151474Z","steps":["trace[1641717583] 'process raft request' (duration: 629.426421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:48.151525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:47.521108Z","time spent":"630.397726ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:45:48.151542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.537498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T13:45:48.151541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:47.612319Z","time spent":"539.177047ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T13:45:48.151563Z","caller":"traceutil/trace.go:171","msg":"trace[260361174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"293.56108ms","start":"2026-05-05T13:45:47.857996Z","end":"2026-05-05T13:45:48.151557Z","steps":["trace[260361174] 'agreement among raft nodes before linearized reading' (duration: 293.505337ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:48.151621Z","caller":"traceutil/trace.go:171","msg":"trace[724712003] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"561.309954ms","start":"2026-05-05T13:45:47.590302Z","end":"2026-05-05T13:45:48.151612Z","steps":["trace[724712003] 'process raft request' (duration: 560.481102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:48.151484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:47.703655Z","time spent":"447.810452ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:45:48.151031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:47.523147Z","time spent":"627.839781ms","remote":"127.0.0.1:38082","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-05T13:45:48.151753Z","caller":"traceutil/trace.go:171","msg":"trace[744719015] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"629.503084ms","start":"2026-05-05T13:45:47.522144Z","end":"2026-05-05T13:45:48.151647Z","steps":["trace[744719015] 'process raft request' (duration: 628.444226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:48.151796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:47.590291Z","time spent":"561.353476ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:45:48.151891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:47.522113Z","time spent":"629.693879ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:45:48.157522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.7694ms","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-05T13:45:48.157576Z","caller":"traceutil/trace.go:171","msg":"trace[620993557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"253.882624ms","start":"2026-05-05T13:45:47.903681Z","end":"2026-05-05T13:45:48.157563Z","steps":["trace[620993557] 'agreement among raft nodes before linearized reading' (duration: 253.73969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:54.441839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.036906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:45:54.442063Z","caller":"traceutil/trace.go:171","msg":"trace[935749696] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"167.07821ms","start":"2026-05-05T13:45:54.274939Z","end":"2026-05-05T13:45:54.442017Z","steps":["trace[935749696] 'process raft request' (duration: 37.779666ms)","trace[935749696] 'compare' (duration: 128.920623ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:45:55.240814Z","caller":"traceutil/trace.go:171","msg":"trace[1142443656] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"482.748196ms","start":"2026-05-05T13:45:54.758042Z","end":"2026-05-05T13:45:55.24079Z","steps":["trace[1142443656] 'read index received' (duration: 481.936484ms)","trace[1142443656] 'applied index is now lower than readState.Index' (duration: 810.442µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:45:55.240917Z","caller":"traceutil/trace.go:171","msg":"trace[1230492239] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"779.041706ms","start":"2026-05-05T13:45:54.46184Z","end":"2026-05-05T13:45:55.240882Z","steps":["trace[1230492239] 'process raft request' (duration: 777.522457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:55.241024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.961921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T13:45:55.241059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:54.461823Z","time spent":"779.164479ms","remote":"127.0.0.1:38198","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-05T13:45:55.24168Z","caller":"traceutil/trace.go:171","msg":"trace[1004801806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"483.010712ms","start":"2026-05-05T13:45:54.758036Z","end":"2026-05-05T13:45:55.241047Z","steps":["trace[1004801806] 'agreement among raft nodes before linearized reading' (duration: 482.867729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:55.24175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:54.757987Z","time spent":"483.745581ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T13:45:55.652894Z","caller":"traceutil/trace.go:171","msg":"trace[849783313] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"411.924677ms","start":"2026-05-05T13:45:55.240878Z","end":"2026-05-05T13:45:55.652803Z","steps":["trace[849783313] 'read index received' (duration: 408.2103ms)","trace[849783313] 'applied index is now lower than readState.Index' (duration: 3.712167ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:45:55.653167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.714785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T13:45:55.65333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.451465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T13:45:55.653372Z","caller":"traceutil/trace.go:171","msg":"trace[508891594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"794.826278ms","start":"2026-05-05T13:45:54.858396Z","end":"2026-05-05T13:45:55.653222Z","steps":["trace[508891594] 'agreement among raft nodes before linearized reading' (duration: 794.594251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:55.653464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:54.858386Z","time spent":"795.056674ms","remote":"127.0.0.1:37994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T13:45:55.65348Z","caller":"traceutil/trace.go:171","msg":"trace[1832266543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"480.606808ms","start":"2026-05-05T13:45:55.172819Z","end":"2026-05-05T13:45:55.653425Z","steps":["trace[1832266543] 'agreement among raft nodes before linearized reading' (duration: 480.26946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:55.653548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:55.172807Z","time spent":"480.724842ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T13:45:55.653145Z","caller":"traceutil/trace.go:171","msg":"trace[1616776982] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"798.225367ms","start":"2026-05-05T13:45:54.854861Z","end":"2026-05-05T13:45:55.653086Z","steps":["trace[1616776982] 'process raft request' (duration: 794.785407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:55.654523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:54.854823Z","time spent":"799.581791ms","remote":"127.0.0.1:38128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:45:55.654989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.145387ms","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-05-05T13:45:55.655051Z","caller":"traceutil/trace.go:171","msg":"trace[1420145690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"485.23276ms","start":"2026-05-05T13:45:55.169793Z","end":"2026-05-05T13:45:55.655026Z","steps":["trace[1420145690] 'agreement among raft nodes before linearized reading' (duration: 484.300186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:55.65509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:55.169774Z","time spent":"485.305332ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T13:45:57.331924Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.500365906s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T13:45:57.657847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000546525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-05T13:45:57.658086Z","caller":"traceutil/trace.go:171","msg":"trace[853849657] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000847873s","start":"2026-05-05T13:45:55.657206Z","end":"2026-05-05T13:45:57.658054Z","steps":["trace[853849657] 'agreement among raft nodes before linearized reading' (duration: 2.000564496s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:57.658164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:55.657189Z","time spent":"2.000958016s","remote":"127.0.0.1:38008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/05 13:45:57 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-05T13:45:58.244403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.412771721s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:45:58.244529Z","caller":"traceutil/trace.go:171","msg":"trace[859018812] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"2.999426745s","start":"2026-05-05T13:45:55.245072Z","end":"2026-05-05T13:45:58.244499Z","steps":["trace[859018812] 'process raft request' (duration: 586.48811ms)","trace[859018812] 'compare' (duration: 2.412449812s)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:45:58.24463Z","caller":"traceutil/trace.go:171","msg":"trace[306396426] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"2.587362245s","start":"2026-05-05T13:45:55.65724Z","end":"2026-05-05T13:45:58.244602Z","steps":["trace[306396426] 'read index received' (duration: 174.34221ms)","trace[306396426] 'applied index is now lower than readState.Index' (duration: 2.413017825s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:45:58.244675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:55.245053Z","time spent":"2.999541528s","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:45:58.244757Z","caller":"traceutil/trace.go:171","msg":"trace[1768362940] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"2.585348902s","start":"2026-05-05T13:45:55.659399Z","end":"2026-05-05T13:45:58.244748Z","steps":["trace[1768362940] 'process raft request' (duration: 2.585194448s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:58.244844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:55.659391Z","time spent":"2.585392123s","remote":"127.0.0.1:38266","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-05T13:45:58.244988Z","caller":"traceutil/trace.go:171","msg":"trace[1056921721] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"2.586072841s","start":"2026-05-05T13:45:55.658895Z","end":"2026-05-05T13:45:58.244968Z","steps":["trace[1056921721] 'process raft request' (duration: 2.585599759s)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:58.245017Z","caller":"traceutil/trace.go:171","msg":"trace[231813301] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"2.53236214s","start":"2026-05-05T13:45:55.712642Z","end":"2026-05-05T13:45:58.245004Z","steps":["trace[231813301] 'process raft request' (duration: 2.531979s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:58.245041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:55.658884Z","time spent":"2.586129793s","remote":"127.0.0.1:38266","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-05T13:45:58.245079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:55.712617Z","time spent":"2.532434321s","remote":"127.0.0.1:38232","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-05T13:45:58.265371Z","caller":"traceutil/trace.go:171","msg":"trace[873597693] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"604.096771ms","start":"2026-05-05T13:45:57.661251Z","end":"2026-05-05T13:45:58.265348Z","steps":["trace[873597693] 'process raft request' (duration: 603.840754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:58.265508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:57.661225Z","time spent":"604.217384ms","remote":"127.0.0.1:38116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:45:58.265777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.223897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:45:58.265953Z","caller":"traceutil/trace.go:171","msg":"trace[1936599534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"598.398622ms","start":"2026-05-05T13:45:57.667538Z","end":"2026-05-05T13:45:58.265937Z","steps":["trace[1936599534] 'agreement among raft nodes before linearized reading' (duration: 598.181136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:58.266026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:57.667499Z","time spent":"598.514315ms","remote":"127.0.0.1:38008","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-05T13:45:58.266004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.466298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:45:58.266072Z","caller":"traceutil/trace.go:171","msg":"trace[1792179244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"784.570839ms","start":"2026-05-05T13:45:57.481487Z","end":"2026-05-05T13:45:58.266058Z","steps":["trace[1792179244] 'agreement among raft nodes before linearized reading' (duration: 784.477587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:58.266108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:57.481468Z","time spent":"784.631261ms","remote":"127.0.0.1:37994","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-05T13:45:58.266354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.039360907s","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-05T13:45:58.266423Z","caller":"traceutil/trace.go:171","msg":"trace[2138159411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"2.03947592s","start":"2026-05-05T13:45:56.226924Z","end":"2026-05-05T13:45:58.2664Z","steps":["trace[2138159411] 'agreement among raft nodes before linearized reading' (duration: 2.039303436s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:58.266452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:56.226905Z","time spent":"2.039538542s","remote":"127.0.0.1:38266","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":"info","ts":"2026-05-05T13:45:59.84472Z","caller":"traceutil/trace.go:171","msg":"trace[1129953376] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"321.843747ms","start":"2026-05-05T13:45:59.52285Z","end":"2026-05-05T13:45:59.844694Z","steps":["trace[1129953376] 'read index received' (duration: 321.464527ms)","trace[1129953376] 'applied index is now lower than readState.Index' (duration: 378.11µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:45:59.845042Z","caller":"traceutil/trace.go:171","msg":"trace[780406591] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"322.273428ms","start":"2026-05-05T13:45:59.522687Z","end":"2026-05-05T13:45:59.84496Z","steps":["trace[780406591] 'process raft request' (duration: 321.690003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:59.845054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.180586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:45:59.8453Z","caller":"traceutil/trace.go:171","msg":"trace[2095256693] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:649; }","duration":"322.424312ms","start":"2026-05-05T13:45:59.522837Z","end":"2026-05-05T13:45:59.845261Z","steps":["trace[2095256693] 'agreement among raft nodes before linearized reading' (duration: 322.144154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:59.845371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:59.522832Z","time spent":"322.515505ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-05-05T13:45:59.845336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:59.522676Z","time spent":"322.461982ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:45:59.851788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.536106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:45:59.851872Z","caller":"traceutil/trace.go:171","msg":"trace[2026701917] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:651; }","duration":"327.643408ms","start":"2026-05-05T13:45:59.52421Z","end":"2026-05-05T13:45:59.851854Z","steps":["trace[2026701917] 'agreement among raft nodes before linearized reading' (duration: 327.469634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:59.851908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.821421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T13:45:59.851923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:59.524206Z","time spent":"327.70785ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-05-05T13:45:59.851944Z","caller":"traceutil/trace.go:171","msg":"trace[845538748] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:651; }","duration":"128.906973ms","start":"2026-05-05T13:45:59.723028Z","end":"2026-05-05T13:45:59.851935Z","steps":["trace[845538748] 'agreement among raft nodes before linearized reading' (duration: 128.837471ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:59.85204Z","caller":"traceutil/trace.go:171","msg":"trace[2012218357] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"325.358299ms","start":"2026-05-05T13:45:59.526669Z","end":"2026-05-05T13:45:59.852027Z","steps":["trace[2012218357] 'process raft request' (duration: 324.850847ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:59.852088Z","caller":"traceutil/trace.go:171","msg":"trace[236745749] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"321.515099ms","start":"2026-05-05T13:45:59.530566Z","end":"2026-05-05T13:45:59.852081Z","steps":["trace[236745749] 'process raft request' (duration: 321.067868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:59.852149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:59.530553Z","time spent":"321.57477ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:45:59.852187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:59.526649Z","time spent":"325.451001ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:45:59.852236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.56297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T13:45:59.852235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.893256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:45:59.852263Z","caller":"traceutil/trace.go:171","msg":"trace[410472593] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:651; }","duration":"321.591611ms","start":"2026-05-05T13:45:59.53066Z","end":"2026-05-05T13:45:59.852251Z","steps":["trace[410472593] 'agreement among raft nodes before linearized reading' (duration: 321.554199ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:45:59.852269Z","caller":"traceutil/trace.go:171","msg":"trace[1242384890] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:651; }","duration":"327.955137ms","start":"2026-05-05T13:45:59.524304Z","end":"2026-05-05T13:45:59.85226Z","steps":["trace[1242384890] 'agreement among raft nodes before linearized reading' (duration: 327.900385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:59.852284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:59.530657Z","time spent":"321.622922ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-05T13:45:59.852274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.771522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:45:59.852364Z","caller":"traceutil/trace.go:171","msg":"trace[1261838669] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:651; }","duration":"327.940557ms","start":"2026-05-05T13:45:59.52441Z","end":"2026-05-05T13:45:59.85235Z","steps":["trace[1261838669] 'agreement among raft nodes before linearized reading' (duration: 327.758592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:59.852296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:59.52429Z","time spent":"327.998709ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-05-05T13:45:59.852427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:59.524385Z","time spent":"328.031919ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-05-05T13:45:59.852802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.135505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:45:59.853416Z","caller":"traceutil/trace.go:171","msg":"trace[1256185427] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:651; }","duration":"322.765991ms","start":"2026-05-05T13:45:59.530632Z","end":"2026-05-05T13:45:59.853398Z","steps":["trace[1256185427] 'agreement among raft nodes before linearized reading' (duration: 322.122734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:45:59.853481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:45:59.530626Z","time spent":"322.836953ms","remote":"127.0.0.1:38082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-05-05T13:46:02.689742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.279704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-05-05T13:46:02.690147Z","caller":"traceutil/trace.go:171","msg":"trace[1332398020] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:709; }","duration":"167.761698ms","start":"2026-05-05T13:46:02.522353Z","end":"2026-05-05T13:46:02.690114Z","steps":["trace[1332398020] 'range keys from in-memory index tree' (duration: 167.033848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:46:13.588107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.713292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T13:46:13.588196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132682503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dcrkh\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-05T13:46:13.588198Z","caller":"traceutil/trace.go:171","msg":"trace[1708409304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"728.849746ms","start":"2026-05-05T13:46:12.859329Z","end":"2026-05-05T13:46:13.588179Z","steps":["trace[1708409304] 'range keys from in-memory index tree' (duration: 728.553638ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:13.588228Z","caller":"traceutil/trace.go:171","msg":"trace[1905068536] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dcrkh; range_end:; response_count:1; response_revision:786; }","duration":"1.132721614s","start":"2026-05-05T13:46:12.455498Z","end":"2026-05-05T13:46:13.588219Z","steps":["trace[1905068536] 'range keys from in-memory index tree' (duration: 1.132443026s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:46:13.588238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:46:12.859304Z","time spent":"728.925498ms","remote":"127.0.0.1:37994","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-05T13:46:13.58825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:46:12.455377Z","time spent":"1.132867197s","remote":"127.0.0.1:38198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dcrkh\" "} {"level":"info","ts":"2026-05-05T13:46:13.836443Z","caller":"traceutil/trace.go:171","msg":"trace[616372266] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"239.125119ms","start":"2026-05-05T13:46:13.597299Z","end":"2026-05-05T13:46:13.836424Z","steps":["trace[616372266] 'process raft request' (duration: 239.007226ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:49.008721Z","caller":"traceutil/trace.go:171","msg":"trace[1210500507] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"150.445355ms","start":"2026-05-05T13:46:48.858255Z","end":"2026-05-05T13:46:49.0087Z","steps":["trace[1210500507] 'read index received' (duration: 150.212909ms)","trace[1210500507] 'applied index is now lower than readState.Index' (duration: 231.566µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:46:49.009537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.271206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:46:49.009636Z","caller":"traceutil/trace.go:171","msg":"trace[1323694936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"151.41052ms","start":"2026-05-05T13:46:48.858204Z","end":"2026-05-05T13:46:49.009615Z","steps":["trace[1323694936] 'agreement among raft nodes before linearized reading' (duration: 150.586799ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:49.009782Z","caller":"traceutil/trace.go:171","msg":"trace[2061182187] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"301.318661ms","start":"2026-05-05T13:46:48.708408Z","end":"2026-05-05T13:46:49.009726Z","steps":["trace[2061182187] 'process raft request' (duration: 300.133889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:46:49.010007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:46:48.708378Z","time spent":"301.489334ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:46:49.819347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.623946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:46:49.819413Z","caller":"traceutil/trace.go:171","msg":"trace[1313060667] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1003; }","duration":"264.739709ms","start":"2026-05-05T13:46:49.554659Z","end":"2026-05-05T13:46:49.819399Z","steps":["trace[1313060667] 'count revisions from in-memory index tree' (duration: 264.545583ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:49.820039Z","caller":"traceutil/trace.go:171","msg":"trace[1295562983] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"126.768028ms","start":"2026-05-05T13:46:49.693238Z","end":"2026-05-05T13:46:49.820006Z","steps":["trace[1295562983] 'process raft request' (duration: 97.153468ms)","trace[1295562983] 'compare' (duration: 29.457247ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:46:50.618601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.35123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T13:46:50.61868Z","caller":"traceutil/trace.go:171","msg":"trace[396806024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"437.483604ms","start":"2026-05-05T13:46:50.181177Z","end":"2026-05-05T13:46:50.618661Z","steps":["trace[396806024] 'range keys from in-memory index tree' (duration: 437.113033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:46:50.618719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:46:50.181153Z","time spent":"437.557346ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T13:47:14.219082Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.116109374s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T13:47:14.219686Z","caller":"traceutil/trace.go:171","msg":"trace[1275071988] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1125; }","duration":"360.718187ms","start":"2026-05-05T13:47:13.85894Z","end":"2026-05-05T13:47:14.219658Z","steps":["trace[1275071988] 'read index received' (duration: 360.441291ms)","trace[1275071988] 'applied index is now lower than readState.Index' (duration: 275.746µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:47:14.219863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.894973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:47:14.219892Z","caller":"traceutil/trace.go:171","msg":"trace[1470868088] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"1.117028968s","start":"2026-05-05T13:47:13.102814Z","end":"2026-05-05T13:47:14.219843Z","steps":["trace[1470868088] 'process raft request' (duration: 1.116684598s)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:14.219927Z","caller":"traceutil/trace.go:171","msg":"trace[529987859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1083; }","duration":"360.999496ms","start":"2026-05-05T13:47:13.85891Z","end":"2026-05-05T13:47:14.21991Z","steps":["trace[529987859] 'agreement among raft nodes before linearized reading' (duration: 360.842122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:14.21999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:13.858894Z","time spent":"361.079427ms","remote":"127.0.0.1:37994","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-05T13:47:14.220076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:13.102788Z","time spent":"1.117187692s","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:47:14.588682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.475852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:47:14.588762Z","caller":"traceutil/trace.go:171","msg":"trace[1287548820] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1083; }","duration":"201.600177ms","start":"2026-05-05T13:47:14.387144Z","end":"2026-05-05T13:47:14.588744Z","steps":["trace[1287548820] 'count revisions from in-memory index tree' (duration: 201.413901ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:14.973071Z","caller":"traceutil/trace.go:171","msg":"trace[1198458413] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"116.258376ms","start":"2026-05-05T13:47:14.85679Z","end":"2026-05-05T13:47:14.973048Z","steps":["trace[1198458413] 'process raft request' (duration: 45.279958ms)","trace[1198458413] 'compare' (duration: 70.839514ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:14.973512Z","caller":"traceutil/trace.go:171","msg":"trace[1505573459] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"115.833194ms","start":"2026-05-05T13:47:14.857646Z","end":"2026-05-05T13:47:14.97348Z","steps":["trace[1505573459] 'process raft request' (duration: 115.369531ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:16.659992Z","caller":"traceutil/trace.go:171","msg":"trace[2105103772] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"651.609808ms","start":"2026-05-05T13:47:16.008358Z","end":"2026-05-05T13:47:16.659967Z","steps":["trace[2105103772] 'process raft request' (duration: 651.440044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:16.660145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:16.008337Z","time spent":"651.73308ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:47:16.692805Z","caller":"traceutil/trace.go:171","msg":"trace[1564423131] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"467.426235ms","start":"2026-05-05T13:47:16.225358Z","end":"2026-05-05T13:47:16.692784Z","steps":["trace[1564423131] 'process raft request' (duration: 467.201879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:16.692964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:16.225321Z","time spent":"467.569228ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:47:17.016501Z","caller":"traceutil/trace.go:171","msg":"trace[78050378] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"144.052497ms","start":"2026-05-05T13:47:16.872423Z","end":"2026-05-05T13:47:17.016476Z","steps":["trace[78050378] 'process raft request' (duration: 143.804151ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:17.169062Z","caller":"traceutil/trace.go:171","msg":"trace[8130232] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"147.117548ms","start":"2026-05-05T13:47:17.021917Z","end":"2026-05-05T13:47:17.169035Z","steps":["trace[8130232] 'process raft request' (duration: 125.895967ms)","trace[8130232] 'compare' (duration: 20.983245ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:47:17.682402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.134784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:47:17.68254Z","caller":"traceutil/trace.go:171","msg":"trace[782314441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"201.328219ms","start":"2026-05-05T13:47:17.481187Z","end":"2026-05-05T13:47:17.682515Z","steps":["trace[782314441] 'range keys from in-memory index tree' (duration: 201.069002ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:29.217989Z","caller":"traceutil/trace.go:171","msg":"trace[210265359] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"112.157497ms","start":"2026-05-05T13:47:29.105792Z","end":"2026-05-05T13:47:29.21795Z","steps":["trace[210265359] 'process raft request' (duration: 76.996455ms)","trace[210265359] 'compare' (duration: 34.901945ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:31.067646Z","caller":"traceutil/trace.go:171","msg":"trace[1341251530] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"446.179173ms","start":"2026-05-05T13:47:30.621437Z","end":"2026-05-05T13:47:31.067616Z","steps":["trace[1341251530] 'process raft request' (duration: 446.031688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:31.067829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:30.621407Z","time spent":"446.342176ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:47:31.070991Z","caller":"traceutil/trace.go:171","msg":"trace[779020784] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1234; }","duration":"212.349093ms","start":"2026-05-05T13:47:30.858596Z","end":"2026-05-05T13:47:31.070946Z","steps":["trace[779020784] 'read index received' (duration: 208.999406ms)","trace[779020784] 'applied index is now lower than readState.Index' (duration: 3.348397ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:47:31.071102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.490107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T13:47:31.071286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.761853ms","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-05T13:47:31.071051Z","caller":"traceutil/trace.go:171","msg":"trace[1472740467] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"333.351449ms","start":"2026-05-05T13:47:30.737641Z","end":"2026-05-05T13:47:31.070992Z","steps":["trace[1472740467] 'process raft request' (duration: 333.215425ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:31.071316Z","caller":"traceutil/trace.go:171","msg":"trace[1524644081] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1187; }","duration":"151.820144ms","start":"2026-05-05T13:47:30.919488Z","end":"2026-05-05T13:47:31.071308Z","steps":["trace[1524644081] 'agreement among raft nodes before linearized reading' (duration: 151.717762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:31.071465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:30.737616Z","time spent":"333.721318ms","remote":"127.0.0.1:38266","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-05T13:47:31.071536Z","caller":"traceutil/trace.go:171","msg":"trace[1740652784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1187; }","duration":"212.61197ms","start":"2026-05-05T13:47:30.858561Z","end":"2026-05-05T13:47:31.071173Z","steps":["trace[1740652784] 'agreement among raft nodes before linearized reading' (duration: 212.499067ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:47.132378Z","caller":"traceutil/trace.go:171","msg":"trace[305918022] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"126.035226ms","start":"2026-05-05T13:47:47.006023Z","end":"2026-05-05T13:47:47.132058Z","steps":["trace[305918022] 'process raft request' (duration: 125.85342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:47.92747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.018596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:47:47.927709Z","caller":"traceutil/trace.go:171","msg":"trace[1361762305] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1311; }","duration":"593.653993ms","start":"2026-05-05T13:47:47.334035Z","end":"2026-05-05T13:47:47.927689Z","steps":["trace[1361762305] 'read index received' (duration: 188.276158ms)","trace[1361762305] 'applied index is now lower than readState.Index' (duration: 405.376705ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:47.927831Z","caller":"traceutil/trace.go:171","msg":"trace[339623599] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"701.097597ms","start":"2026-05-05T13:47:47.226717Z","end":"2026-05-05T13:47:47.927815Z","steps":["trace[339623599] 'process raft request' (duration: 295.6381ms)","trace[339623599] 'compare' (duration: 404.605095ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:47.927896Z","caller":"traceutil/trace.go:171","msg":"trace[2022241174] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"701.074246ms","start":"2026-05-05T13:47:47.226786Z","end":"2026-05-05T13:47:47.927861Z","steps":["trace[2022241174] 'process raft request' (duration: 700.791569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:47.927969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:47.226693Z","time spent":"701.233992ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:47:47.927966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.9149ms","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-05T13:47:47.927994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.432842ms","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-05T13:47:47.928024Z","caller":"traceutil/trace.go:171","msg":"trace[822147867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1261; }","duration":"594.010513ms","start":"2026-05-05T13:47:47.334Z","end":"2026-05-05T13:47:47.928011Z","steps":["trace[822147867] 'agreement among raft nodes before linearized reading' (duration: 593.836998ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:47.928027Z","caller":"traceutil/trace.go:171","msg":"trace[1166615019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1261; }","duration":"592.503524ms","start":"2026-05-05T13:47:47.335515Z","end":"2026-05-05T13:47:47.928019Z","steps":["trace[1166615019] 'agreement among raft nodes before linearized reading' (duration: 592.380811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:47.928053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:47.335499Z","time spent":"592.550206ms","remote":"127.0.0.1:38266","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":"warn","ts":"2026-05-05T13:47:47.928053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:47.333985Z","time spent":"594.062885ms","remote":"127.0.0.1:38266","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-05T13:47:47.928048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:47.226782Z","time spent":"701.185651ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:47:47.928147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.486542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:47:47.928159Z","caller":"traceutil/trace.go:171","msg":"trace[1271524010] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1261; }","duration":"563.523942ms","start":"2026-05-05T13:47:47.364631Z","end":"2026-05-05T13:47:47.928155Z","steps":["trace[1271524010] 'agreement among raft nodes before linearized reading' (duration: 563.481881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:47.928172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:47.364614Z","time spent":"563.554414ms","remote":"127.0.0.1:38206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-05T13:47:47.928208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.155675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-05-05T13:47:47.928242Z","caller":"traceutil/trace.go:171","msg":"trace[2029275232] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1261; }","duration":"521.209135ms","start":"2026-05-05T13:47:47.407019Z","end":"2026-05-05T13:47:47.928228Z","steps":["trace[2029275232] 'agreement among raft nodes before linearized reading' (duration: 521.081642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:47.928273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:47.407005Z","time spent":"521.259668ms","remote":"127.0.0.1:38188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-05T13:47:48.504679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15855096178292147426,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T13:47:48.55069Z","caller":"traceutil/trace.go:171","msg":"trace[672830090] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"618.641112ms","start":"2026-05-05T13:47:47.932019Z","end":"2026-05-05T13:47:48.55066Z","steps":["trace[672830090] 'process raft request' (duration: 618.439527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:48.550911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:47.931995Z","time spent":"618.821786ms","remote":"127.0.0.1:38266","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-05T13:47:48.58986Z","caller":"traceutil/trace.go:171","msg":"trace[212584990] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"654.88735ms","start":"2026-05-05T13:47:47.934953Z","end":"2026-05-05T13:47:48.589841Z","steps":["trace[212584990] 'process raft request' (duration: 654.764187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:48.590015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:47.934941Z","time spent":"654.999683ms","remote":"127.0.0.1:38188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:47:48.589889Z","caller":"traceutil/trace.go:171","msg":"trace[695941549] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"657.299964ms","start":"2026-05-05T13:47:47.932562Z","end":"2026-05-05T13:47:48.589862Z","steps":["trace[695941549] 'process raft request' (duration: 657.027317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:48.590126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:47.932545Z","time spent":"657.524199ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:47:48.590562Z","caller":"traceutil/trace.go:171","msg":"trace[475753799] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1313; }","duration":"585.573523ms","start":"2026-05-05T13:47:48.004197Z","end":"2026-05-05T13:47:48.58977Z","steps":["trace[475753799] 'read index received' (duration: 546.231244ms)","trace[475753799] 'applied index is now lower than readState.Index' (duration: 39.339109ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:47:48.590879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.837512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:47:48.591011Z","caller":"traceutil/trace.go:171","msg":"trace[1437230390] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1264; }","duration":"586.783387ms","start":"2026-05-05T13:47:48.004152Z","end":"2026-05-05T13:47:48.590935Z","steps":["trace[1437230390] 'agreement among raft nodes before linearized reading' (duration: 585.7756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:48.591254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.202866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:47:48.591317Z","caller":"traceutil/trace.go:171","msg":"trace[1736132710] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1264; }","duration":"472.3248ms","start":"2026-05-05T13:47:48.118974Z","end":"2026-05-05T13:47:48.591299Z","steps":["trace[1736132710] 'agreement among raft nodes before linearized reading' (duration: 472.146556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:48.59137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:48.118938Z","time spent":"472.416652ms","remote":"127.0.0.1:54736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-05T13:47:48.591316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:48.004131Z","time spent":"587.121975ms","remote":"127.0.0.1:40074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-05-05T13:47:49.599901Z","caller":"traceutil/trace.go:171","msg":"trace[1691509568] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"458.467141ms","start":"2026-05-05T13:47:49.141404Z","end":"2026-05-05T13:47:49.599871Z","steps":["trace[1691509568] 'process raft request' (duration: 431.710108ms)","trace[1691509568] 'compare' (duration: 26.543797ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:47:49.600039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:49.141387Z","time spent":"458.583963ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":456,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:47:50.364544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.10189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:47:50.365219Z","caller":"traceutil/trace.go:171","msg":"trace[1751349898] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"759.383658ms","start":"2026-05-05T13:47:49.605817Z","end":"2026-05-05T13:47:50.3652Z","steps":["trace[1751349898] 'process raft request' (duration: 759.340617ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:50.365349Z","caller":"traceutil/trace.go:171","msg":"trace[714103187] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1320; }","duration":"1.121495762s","start":"2026-05-05T13:47:49.243839Z","end":"2026-05-05T13:47:50.365334Z","steps":["trace[714103187] 'read index received' (duration: 329.255322ms)","trace[714103187] 'applied index is now lower than readState.Index' (duration: 792.2387ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:50.365342Z","caller":"traceutil/trace.go:171","msg":"trace[868208785] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"1.223635959s","start":"2026-05-05T13:47:49.141659Z","end":"2026-05-05T13:47:50.365295Z","steps":["trace[868208785] 'process raft request' (duration: 957.698538ms)","trace[868208785] 'compare' (duration: 264.895065ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:50.365346Z","caller":"traceutil/trace.go:171","msg":"trace[1380755091] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"1.205336235s","start":"2026-05-05T13:47:49.15998Z","end":"2026-05-05T13:47:50.365316Z","steps":["trace[1380755091] 'process raft request' (duration: 1.205078439s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:50.365506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121657286s","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-05T13:47:50.365545Z","caller":"traceutil/trace.go:171","msg":"trace[1447444056] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"1.121739908s","start":"2026-05-05T13:47:49.243798Z","end":"2026-05-05T13:47:50.365538Z","steps":["trace[1447444056] 'agreement among raft nodes before linearized reading' (duration: 1.121595535s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:50.365537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:49.159952Z","time spent":"1.20552038s","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:47:50.365573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:49.243783Z","time spent":"1.121784029s","remote":"127.0.0.1:38266","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-05T13:47:50.365516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:49.141638Z","time spent":"1.223797223s","remote":"127.0.0.1:38116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:47:50.365708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.060168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"info","ts":"2026-05-05T13:47:50.36573Z","caller":"traceutil/trace.go:171","msg":"trace[392332864] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1272; }","duration":"762.11855ms","start":"2026-05-05T13:47:49.603606Z","end":"2026-05-05T13:47:50.365724Z","steps":["trace[392332864] 'agreement among raft nodes before linearized reading' (duration: 762.062779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:50.365748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:49.603588Z","time spent":"762.15588ms","remote":"127.0.0.1:38266","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-05-05T13:47:50.365805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.240332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:47:50.365825Z","caller":"traceutil/trace.go:171","msg":"trace[1851489789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"510.283393ms","start":"2026-05-05T13:47:49.855536Z","end":"2026-05-05T13:47:50.36582Z","steps":["trace[1851489789] 'agreement among raft nodes before linearized reading' (duration: 510.255513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:50.365842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:49.85552Z","time spent":"510.318694ms","remote":"127.0.0.1:37994","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-05T13:47:50.365831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.73221ms","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-05T13:47:50.365886Z","caller":"traceutil/trace.go:171","msg":"trace[1298599516] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1272; }","duration":"433.863504ms","start":"2026-05-05T13:47:49.932007Z","end":"2026-05-05T13:47:50.36587Z","steps":["trace[1298599516] 'agreement among raft nodes before linearized reading' (duration: 433.696439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:50.365943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:49.931982Z","time spent":"433.948765ms","remote":"127.0.0.1:38266","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-05T13:47:50.366193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.041568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T13:47:50.366285Z","caller":"traceutil/trace.go:171","msg":"trace[2037365258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1272; }","duration":"434.14868ms","start":"2026-05-05T13:47:49.932083Z","end":"2026-05-05T13:47:50.366231Z","steps":["trace[2037365258] 'agreement among raft nodes before linearized reading' (duration: 433.937175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:50.366347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:49.932051Z","time spent":"434.281615ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T13:47:50.366493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:49.605798Z","time spent":"759.470261ms","remote":"127.0.0.1:38116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":770,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T13:47:50.513482Z","caller":"traceutil/trace.go:171","msg":"trace[1479800311] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"144.089724ms","start":"2026-05-05T13:47:50.369349Z","end":"2026-05-05T13:47:50.513438Z","steps":["trace[1479800311] 'process raft request' (duration: 95.834953ms)","trace[1479800311] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:461; } (duration: 47.900571ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:50.545989Z","caller":"traceutil/trace.go:171","msg":"trace[1574807928] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"175.326462ms","start":"2026-05-05T13:47:50.370644Z","end":"2026-05-05T13:47:50.54597Z","steps":["trace[1574807928] 'process raft request' (duration: 175.160558ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:50.546172Z","caller":"traceutil/trace.go:171","msg":"trace[1685400863] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"175.418925ms","start":"2026-05-05T13:47:50.370747Z","end":"2026-05-05T13:47:50.546166Z","steps":["trace[1685400863] 'process raft request' (duration: 175.151738ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:50.546307Z","caller":"traceutil/trace.go:171","msg":"trace[2035533401] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"175.514038ms","start":"2026-05-05T13:47:50.370783Z","end":"2026-05-05T13:47:50.546297Z","steps":["trace[2035533401] 'process raft request' (duration: 175.357543ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:51.605502Z","caller":"traceutil/trace.go:171","msg":"trace[1788764390] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"125.552644ms","start":"2026-05-05T13:47:51.479924Z","end":"2026-05-05T13:47:51.605477Z","steps":["trace[1788764390] 'process raft request' (duration: 125.118563ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:51.787991Z","caller":"traceutil/trace.go:171","msg":"trace[932424102] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"141.254071ms","start":"2026-05-05T13:47:51.646716Z","end":"2026-05-05T13:47:51.78797Z","steps":["trace[932424102] 'read index received' (duration: 89.454509ms)","trace[932424102] 'applied index is now lower than readState.Index' (duration: 51.798522ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:51.788033Z","caller":"traceutil/trace.go:171","msg":"trace[286043924] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"169.994136ms","start":"2026-05-05T13:47:51.618006Z","end":"2026-05-05T13:47:51.788Z","steps":["trace[286043924] 'process raft request' (duration: 118.163273ms)","trace[286043924] 'compare' (duration: 51.680869ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:47:51.788309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.419685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:47:51.788369Z","caller":"traceutil/trace.go:171","msg":"trace[202399501] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1280; }","duration":"141.673101ms","start":"2026-05-05T13:47:51.646681Z","end":"2026-05-05T13:47:51.788355Z","steps":["trace[202399501] 'agreement among raft nodes before linearized reading' (duration: 141.359323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:03.062888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15855096178292147608,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T13:48:03.178673Z","caller":"traceutil/trace.go:171","msg":"trace[2082902117] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"616.864785ms","start":"2026-05-05T13:48:02.561787Z","end":"2026-05-05T13:48:03.178652Z","steps":["trace[2082902117] 'read index received' (duration: 616.801874ms)","trace[2082902117] 'applied index is now lower than readState.Index' (duration: 61.901µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:48:03.178769Z","caller":"traceutil/trace.go:171","msg":"trace[494303319] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"759.036999ms","start":"2026-05-05T13:48:02.419692Z","end":"2026-05-05T13:48:03.178729Z","steps":["trace[494303319] 'process raft request' (duration: 758.825114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:03.178874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.242055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T13:48:03.178872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.065031ms","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-05T13:48:03.178915Z","caller":"traceutil/trace.go:171","msg":"trace[548599413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1356; }","duration":"585.332598ms","start":"2026-05-05T13:48:02.593573Z","end":"2026-05-05T13:48:03.178905Z","steps":["trace[548599413] 'agreement among raft nodes before linearized reading' (duration: 585.205014ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:03.178928Z","caller":"traceutil/trace.go:171","msg":"trace[1897444867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1356; }","duration":"617.165514ms","start":"2026-05-05T13:48:02.561748Z","end":"2026-05-05T13:48:03.178913Z","steps":["trace[1897444867] 'agreement among raft nodes before linearized reading' (duration: 616.988648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:03.178954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:48:02.593555Z","time spent":"585.39153ms","remote":"127.0.0.1:38266","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-05T13:48:03.178961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:48:02.56173Z","time spent":"617.222875ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T13:48:03.178965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:48:02.419668Z","time spent":"759.188073ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:48:03.179102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.58882ms","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-05T13:48:03.179184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.948464ms","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-05T13:48:03.179231Z","caller":"traceutil/trace.go:171","msg":"trace[1250457367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1356; }","duration":"454.721093ms","start":"2026-05-05T13:48:02.72448Z","end":"2026-05-05T13:48:03.179201Z","steps":["trace[1250457367] 'agreement among raft nodes before linearized reading' (duration: 454.445806ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:03.179266Z","caller":"traceutil/trace.go:171","msg":"trace[777954815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1356; }","duration":"559.052736ms","start":"2026-05-05T13:48:02.620171Z","end":"2026-05-05T13:48:03.179224Z","steps":["trace[777954815] 'agreement among raft nodes before linearized reading' (duration: 558.900953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:03.179278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:48:02.724463Z","time spent":"454.802734ms","remote":"127.0.0.1:38266","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-05T13:48:03.179306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:48:02.620152Z","time spent":"559.144819ms","remote":"127.0.0.1:38266","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":"warn","ts":"2026-05-05T13:48:03.17914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.785968ms","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-05T13:48:03.179456Z","caller":"traceutil/trace.go:171","msg":"trace[1656323948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1356; }","duration":"584.121006ms","start":"2026-05-05T13:48:02.595318Z","end":"2026-05-05T13:48:03.179439Z","steps":["trace[1656323948] 'agreement among raft nodes before linearized reading' (duration: 583.763747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:03.179502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:48:02.595305Z","time spent":"584.182789ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T13:48:46.010381Z","caller":"traceutil/trace.go:171","msg":"trace[1583040480] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"129.904106ms","start":"2026-05-05T13:48:45.880458Z","end":"2026-05-05T13:48:46.010362Z","steps":["trace[1583040480] 'process raft request' (duration: 129.767813ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:47.509037Z","caller":"traceutil/trace.go:171","msg":"trace[962444747] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"105.701293ms","start":"2026-05-05T13:48:47.403293Z","end":"2026-05-05T13:48:47.508994Z","steps":["trace[962444747] 'process raft request' (duration: 105.523919ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:23.764735Z","caller":"traceutil/trace.go:171","msg":"trace[1855417574] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"120.948187ms","start":"2026-05-05T13:49:23.643759Z","end":"2026-05-05T13:49:23.764707Z","steps":["trace[1855417574] 'process raft request' (duration: 112.630622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:49:37.124777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.096531ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T13:49:46.074287Z","caller":"traceutil/trace.go:171","msg":"trace[85157937] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"163.106363ms","start":"2026-05-05T13:49:45.911115Z","end":"2026-05-05T13:49:46.074221Z","steps":["trace[85157937] 'process raft request' (duration: 120.240341ms)","trace[85157937] 'compare' (duration: 42.647806ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:49:46.074561Z","caller":"traceutil/trace.go:171","msg":"trace[1807391775] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"162.673832ms","start":"2026-05-05T13:49:45.911869Z","end":"2026-05-05T13:49:46.074543Z","steps":["trace[1807391775] 'process raft request' (duration: 162.298972ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:46.074961Z","caller":"traceutil/trace.go:171","msg":"trace[539238475] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"162.360814ms","start":"2026-05-05T13:49:45.912247Z","end":"2026-05-05T13:49:46.074608Z","steps":["trace[539238475] 'process raft request' (duration: 162.241981ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:46.223439Z","caller":"traceutil/trace.go:171","msg":"trace[1887894291] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"144.381151ms","start":"2026-05-05T13:49:46.079019Z","end":"2026-05-05T13:49:46.2234Z","steps":["trace[1887894291] 'process raft request' (duration: 100.300578ms)","trace[1887894291] 'compare' (duration: 43.822687ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:49:46.223474Z","caller":"traceutil/trace.go:171","msg":"trace[1681049217] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"144.35908ms","start":"2026-05-05T13:49:46.079082Z","end":"2026-05-05T13:49:46.223441Z","steps":["trace[1681049217] 'process raft request' (duration: 144.192036ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:48.244176Z","caller":"traceutil/trace.go:171","msg":"trace[990064443] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"160.213398ms","start":"2026-05-05T13:49:48.08393Z","end":"2026-05-05T13:49:48.244144Z","steps":["trace[990064443] 'process raft request' (duration: 135.257616ms)","trace[990064443] 'compare' (duration: 24.535461ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:49:50.355974Z","caller":"traceutil/trace.go:171","msg":"trace[273449566] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"101.341825ms","start":"2026-05-05T13:49:50.254584Z","end":"2026-05-05T13:49:50.355926Z","steps":["trace[273449566] 'read index received' (duration: 42.39368ms)","trace[273449566] 'applied index is now lower than readState.Index' (duration: 58.947035ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:49:50.356013Z","caller":"traceutil/trace.go:171","msg":"trace[1536787421] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"102.73074ms","start":"2026-05-05T13:49:50.253269Z","end":"2026-05-05T13:49:50.355999Z","steps":["trace[1536787421] 'process raft request' (duration: 43.793725ms)","trace[1536787421] 'compare' (duration: 58.772211ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:49:50.356452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.86051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T13:49:50.356479Z","caller":"traceutil/trace.go:171","msg":"trace[1017131598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1848; }","duration":"101.939792ms","start":"2026-05-05T13:49:50.254534Z","end":"2026-05-05T13:49:50.356473Z","steps":["trace[1017131598] 'agreement among raft nodes before linearized reading' (duration: 101.546281ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:50.533803Z","caller":"traceutil/trace.go:171","msg":"trace[14455275] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"171.95323ms","start":"2026-05-05T13:49:50.361823Z","end":"2026-05-05T13:49:50.533776Z","steps":["trace[14455275] 'process raft request' (duration: 171.889219ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:50.533915Z","caller":"traceutil/trace.go:171","msg":"trace[2139467891] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"173.533071ms","start":"2026-05-05T13:49:50.360338Z","end":"2026-05-05T13:49:50.533871Z","steps":["trace[2139467891] 'process raft request' (duration: 104.59316ms)","trace[2139467891] 'compare' (duration: 68.640874ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:49:58.799885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.982029ms","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-05T13:49:58.79998Z","caller":"traceutil/trace.go:171","msg":"trace[740409555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1884; }","duration":"175.084661ms","start":"2026-05-05T13:49:58.624877Z","end":"2026-05-05T13:49:58.799962Z","steps":["trace[740409555] 'range keys from in-memory index tree' (duration: 174.836625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:49:58.800646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.848467ms","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-05-05T13:49:58.800804Z","caller":"traceutil/trace.go:171","msg":"trace[339465936] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1884; }","duration":"122.973442ms","start":"2026-05-05T13:49:58.677754Z","end":"2026-05-05T13:49:58.800728Z","steps":["trace[339465936] 'range keys from in-memory index tree' (duration: 122.717864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:49:58.801032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.637994ms","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-05T13:49:58.801103Z","caller":"traceutil/trace.go:171","msg":"trace[279121449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1884; }","duration":"190.800734ms","start":"2026-05-05T13:49:58.610279Z","end":"2026-05-05T13:49:58.801079Z","steps":["trace[279121449] 'range keys from in-memory index tree' (duration: 189.493191ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:58.911671Z","caller":"traceutil/trace.go:171","msg":"trace[1621601707] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"106.962239ms","start":"2026-05-05T13:49:58.804682Z","end":"2026-05-05T13:49:58.911644Z","steps":["trace[1621601707] 'process raft request' (duration: 106.749084ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:58.962523Z","caller":"traceutil/trace.go:171","msg":"trace[13354927] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1962; }","duration":"104.168438ms","start":"2026-05-05T13:49:58.858336Z","end":"2026-05-05T13:49:58.962505Z","steps":["trace[13354927] 'read index received' (duration: 53.246069ms)","trace[13354927] 'applied index is now lower than readState.Index' (duration: 50.921449ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:49:58.962709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.355973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:49:58.962774Z","caller":"traceutil/trace.go:171","msg":"trace[2095007657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1888; }","duration":"104.465016ms","start":"2026-05-05T13:49:58.858297Z","end":"2026-05-05T13:49:58.962762Z","steps":["trace[2095007657] 'agreement among raft nodes before linearized reading' (duration: 104.342203ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:58.963068Z","caller":"traceutil/trace.go:171","msg":"trace[1397815167] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"157.627682ms","start":"2026-05-05T13:49:58.805428Z","end":"2026-05-05T13:49:58.963056Z","steps":["trace[1397815167] 'process raft request' (duration: 156.909513ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:58.963373Z","caller":"traceutil/trace.go:171","msg":"trace[6519699] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"157.88477ms","start":"2026-05-05T13:49:58.805456Z","end":"2026-05-05T13:49:58.963341Z","steps":["trace[6519699] 'process raft request' (duration: 156.981297ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:58.96342Z","caller":"traceutil/trace.go:171","msg":"trace[999593043] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"156.954045ms","start":"2026-05-05T13:49:58.806448Z","end":"2026-05-05T13:49:58.963402Z","steps":["trace[999593043] 'process raft request' (duration: 156.022891ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:50:00.074515Z","caller":"traceutil/trace.go:171","msg":"trace[1875776662] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"213.441527ms","start":"2026-05-05T13:49:59.86104Z","end":"2026-05-05T13:50:00.074482Z","steps":["trace[1875776662] 'process raft request' (duration: 199.212832ms)","trace[1875776662] 'compare' (duration: 14.053571ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:50:01.165511Z","caller":"traceutil/trace.go:171","msg":"trace[2046786103] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"194.129831ms","start":"2026-05-05T13:50:00.971348Z","end":"2026-05-05T13:50:01.165477Z","steps":["trace[2046786103] 'process raft request' (duration: 192.379396ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:50:01.166004Z","caller":"traceutil/trace.go:171","msg":"trace[1391042536] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"193.580046ms","start":"2026-05-05T13:50:00.972027Z","end":"2026-05-05T13:50:01.165607Z","steps":["trace[1391042536] 'process raft request' (duration: 193.268318ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:50:06.37698Z","caller":"traceutil/trace.go:171","msg":"trace[1474203507] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1997; }","duration":"152.843889ms","start":"2026-05-05T13:50:06.224118Z","end":"2026-05-05T13:50:06.376962Z","steps":["trace[1474203507] 'read index received' (duration: 152.639954ms)","trace[1474203507] 'applied index is now lower than readState.Index' (duration: 203.155µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:50:06.377148Z","caller":"traceutil/trace.go:171","msg":"trace[39197799] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"160.179497ms","start":"2026-05-05T13:50:06.216839Z","end":"2026-05-05T13:50:06.377018Z","steps":["trace[39197799] 'process raft request' (duration: 159.961592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:50:06.377211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.066155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"info","ts":"2026-05-05T13:50:06.377301Z","caller":"traceutil/trace.go:171","msg":"trace[875777889] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1919; }","duration":"153.195829ms","start":"2026-05-05T13:50:06.224087Z","end":"2026-05-05T13:50:06.377283Z","steps":["trace[875777889] 'agreement among raft nodes before linearized reading' (duration: 152.953512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:50:07.049149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.814623ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T13:50:07.164797Z","caller":"traceutil/trace.go:171","msg":"trace[340439634] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"111.628671ms","start":"2026-05-05T13:50:07.053144Z","end":"2026-05-05T13:50:07.164773Z","steps":["trace[340439634] 'process raft request' (duration: 111.466306ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:50:07.298819Z","caller":"traceutil/trace.go:171","msg":"trace[1476126371] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"107.092763ms","start":"2026-05-05T13:50:07.191704Z","end":"2026-05-05T13:50:07.298797Z","steps":["trace[1476126371] 'process raft request' (duration: 86.920714ms)","trace[1476126371] 'compare' (duration: 20.053627ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:51:12.580021Z","caller":"traceutil/trace.go:171","msg":"trace[1148277248] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"118.378544ms","start":"2026-05-05T13:51:12.461618Z","end":"2026-05-05T13:51:12.579996Z","steps":["trace[1148277248] 'process raft request' (duration: 118.19414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:12.975653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.909595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T13:51:12.975747Z","caller":"traceutil/trace.go:171","msg":"trace[1838891304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2248; }","duration":"305.06064ms","start":"2026-05-05T13:51:12.670661Z","end":"2026-05-05T13:51:12.975722Z","steps":["trace[1838891304] 'range keys from in-memory index tree' (duration: 304.734042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:12.975793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:51:12.670644Z","time spent":"305.139822ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T13:51:12.976212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.743707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T13:51:12.976319Z","caller":"traceutil/trace.go:171","msg":"trace[1980478693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2248; }","duration":"179.916432ms","start":"2026-05-05T13:51:12.796377Z","end":"2026-05-05T13:51:12.976293Z","steps":["trace[1980478693] 'range keys from in-memory index tree' (duration: 179.536921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:31.727983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.587306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:51:31.728132Z","caller":"traceutil/trace.go:171","msg":"trace[692712743] linearizableReadLoop","detail":"{readStateIndex:2445; appliedIndex:2444; }","duration":"120.91247ms","start":"2026-05-05T13:51:31.607195Z","end":"2026-05-05T13:51:31.728107Z","steps":["trace[692712743] 'read index received' (duration: 8.032827ms)","trace[692712743] 'applied index is now lower than readState.Index' (duration: 112.878163ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:51:31.728246Z","caller":"traceutil/trace.go:171","msg":"trace[1950056466] transaction","detail":"{read_only:false; response_revision:2347; number_of_response:1; }","duration":"122.185831ms","start":"2026-05-05T13:51:31.606023Z","end":"2026-05-05T13:51:31.728208Z","steps":["trace[1950056466] 'compare' (duration: 112.445142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:31.728278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.064803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T13:51:31.728371Z","caller":"traceutil/trace.go:171","msg":"trace[1306298064] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2347; }","duration":"121.188296ms","start":"2026-05-05T13:51:31.607155Z","end":"2026-05-05T13:51:31.728344Z","steps":["trace[1306298064] 'agreement among raft nodes before linearized reading' (duration: 121.042953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:31.97959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.101023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:51:31.979756Z","caller":"traceutil/trace.go:171","msg":"trace[962304869] linearizableReadLoop","detail":"{readStateIndex:2447; appliedIndex:2445; }","duration":"236.380149ms","start":"2026-05-05T13:51:31.74336Z","end":"2026-05-05T13:51:31.97974Z","steps":["trace[962304869] 'read index received' (duration: 124.046589ms)","trace[962304869] 'applied index is now lower than readState.Index' (duration: 112.33249ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:51:31.97984Z","caller":"traceutil/trace.go:171","msg":"trace[1189105206] transaction","detail":"{read_only:false; response_revision:2348; number_of_response:1; }","duration":"247.049622ms","start":"2026-05-05T13:51:31.73278Z","end":"2026-05-05T13:51:31.979829Z","steps":["trace[1189105206] 'process raft request' (duration: 134.61568ms)","trace[1189105206] 'compare' (duration: 111.98105ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:51:31.980004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.642956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T13:51:31.980001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.430028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-account-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-05-05T13:51:31.980051Z","caller":"traceutil/trace.go:171","msg":"trace[415778725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2349; }","duration":"236.715667ms","start":"2026-05-05T13:51:31.74332Z","end":"2026-05-05T13:51:31.980035Z","steps":["trace[415778725] 'agreement among raft nodes before linearized reading' (duration: 236.559444ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:31.980097Z","caller":"traceutil/trace.go:171","msg":"trace[72601524] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-account-controller; range_end:; response_count:1; response_revision:2349; }","duration":"168.52526ms","start":"2026-05-05T13:51:31.811545Z","end":"2026-05-05T13:51:31.980071Z","steps":["trace[72601524] 'agreement among raft nodes before linearized reading' (duration: 168.372267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:31.980086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.274618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-05T13:51:31.980136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.021597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:751"} {"level":"warn","ts":"2026-05-05T13:51:31.980185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.090407ms","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-05-05T13:51:31.980176Z","caller":"traceutil/trace.go:171","msg":"trace[641850924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2349; }","duration":"229.39486ms","start":"2026-05-05T13:51:31.750758Z","end":"2026-05-05T13:51:31.980153Z","steps":["trace[641850924] 'agreement among raft nodes before linearized reading' (duration: 229.142244ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:31.980197Z","caller":"traceutil/trace.go:171","msg":"trace[128798751] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:2349; }","duration":"168.083589ms","start":"2026-05-05T13:51:31.812094Z","end":"2026-05-05T13:51:31.980178Z","steps":["trace[128798751] 'agreement among raft nodes before linearized reading' (duration: 167.963246ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:31.980226Z","caller":"traceutil/trace.go:171","msg":"trace[996672280] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2349; }","duration":"228.168679ms","start":"2026-05-05T13:51:31.752046Z","end":"2026-05-05T13:51:31.980215Z","steps":["trace[996672280] 'agreement among raft nodes before linearized reading' (duration: 228.052466ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:31.980328Z","caller":"traceutil/trace.go:171","msg":"trace[2059989370] transaction","detail":"{read_only:false; response_revision:2349; number_of_response:1; }","duration":"245.566434ms","start":"2026-05-05T13:51:31.734743Z","end":"2026-05-05T13:51:31.980309Z","steps":["trace[2059989370] 'process raft request' (duration: 244.947758ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:42.11342Z","caller":"traceutil/trace.go:171","msg":"trace[503717875] linearizableReadLoop","detail":"{readStateIndex:2494; appliedIndex:2493; }","duration":"111.898886ms","start":"2026-05-05T13:51:42.001504Z","end":"2026-05-05T13:51:42.113403Z","steps":["trace[503717875] 'read index received' (duration: 111.798804ms)","trace[503717875] 'applied index is now lower than readState.Index' (duration: 99.222µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:51:42.113699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.175645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:12 size:90329"} {"level":"info","ts":"2026-05-05T13:51:42.113743Z","caller":"traceutil/trace.go:171","msg":"trace[1366450701] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:12; response_revision:2394; }","duration":"112.254016ms","start":"2026-05-05T13:51:42.001483Z","end":"2026-05-05T13:51:42.113737Z","steps":["trace[1366450701] 'agreement among raft nodes before linearized reading' (duration: 112.001809ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:42.113456Z","caller":"traceutil/trace.go:171","msg":"trace[1453414715] transaction","detail":"{read_only:false; response_revision:2394; number_of_response:1; }","duration":"117.386347ms","start":"2026-05-05T13:51:41.99605Z","end":"2026-05-05T13:51:42.113436Z","steps":["trace[1453414715] 'process raft request' (duration: 117.240093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:42.200995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.191241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-05-05T13:51:42.201105Z","caller":"traceutil/trace.go:171","msg":"trace[1762046972] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:2395; }","duration":"191.339475ms","start":"2026-05-05T13:51:42.009744Z","end":"2026-05-05T13:51:42.201084Z","steps":["trace[1762046972] 'agreement among raft nodes before linearized reading' (duration: 191.11616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:42.201007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.827517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"info","ts":"2026-05-05T13:51:42.201248Z","caller":"traceutil/trace.go:171","msg":"trace[2014997725] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:2395; }","duration":"135.112815ms","start":"2026-05-05T13:51:42.066115Z","end":"2026-05-05T13:51:42.201228Z","steps":["trace[2014997725] 'agreement among raft nodes before linearized reading' (duration: 134.824987ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:42.322392Z","caller":"traceutil/trace.go:171","msg":"trace[1250904536] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"116.30842ms","start":"2026-05-05T13:51:42.206052Z","end":"2026-05-05T13:51:42.322361Z","steps":["trace[1250904536] 'process raft request' (duration: 116.145365ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:42.322392Z","caller":"traceutil/trace.go:171","msg":"trace[2068199073] transaction","detail":"{read_only:false; response_revision:2397; number_of_response:1; }","duration":"116.992308ms","start":"2026-05-05T13:51:42.205335Z","end":"2026-05-05T13:51:42.322327Z","steps":["trace[2068199073] 'process raft request' (duration: 111.015913ms)"],"step_count":1}