{"level":"warn","ts":"2026-07-02T05:10:10.388768Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:10:10.388882Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.204.45.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-02T05:10:10.389093Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:10:10.38911Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-02T05:10:10.389131Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T05:10:10.389892Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-07-02T05:10:10.390044Z","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.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-02T05:10:10.395084Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.717285ms"} {"level":"info","ts":"2026-07-02T05:10:10.400355Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-07-02T05:10:10.400443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T05:10:10.400471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-07-02T05:10:10.400482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T05:10:10.400517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-07-02T05:10:10.4006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-07-02T05:10:10.407564Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T05:10:10.40905Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T05:10:10.410211Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-02T05:10:10.412102Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T05:10:10.413274Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T05:10:10.416183Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T05:10:10.41624Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T05:10:10.416251Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T05:10:10.416522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-07-02T05:10:10.423564Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-02T05:10:10.424564Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T05:10:10.4247Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-02T05:10:10.424728Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-02T05:10:10.425125Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T05:10:10.425175Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T05:10:10.60146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T05:10:10.601537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T05:10:10.60157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-07-02T05:10:10.601611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-07-02T05:10:10.601631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-02T05:10:10.601656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-07-02T05:10:10.601687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-02T05:10:10.602446Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:10:10.60325Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T05:10:10.603282Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:10:10.603377Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:10:10.603522Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:10:10.603772Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:10:10.603814Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:10:10.603775Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T05:10:10.6039Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T05:10:10.607182Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T05:10:10.607794Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-07-02T05:10:15.278185Z","caller":"traceutil/trace.go:171","msg":"trace[1221192417] linearizableReadLoop","detail":"{readStateIndex:228; appliedIndex:227; }","duration":"146.318778ms","start":"2026-07-02T05:10:15.131824Z","end":"2026-07-02T05:10:15.278142Z","steps":["trace[1221192417] 'read index received' (duration: 114.891751ms)","trace[1221192417] 'applied index is now lower than readState.Index' (duration: 31.425307ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:15.278293Z","caller":"traceutil/trace.go:171","msg":"trace[1179411679] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"171.999912ms","start":"2026-07-02T05:10:15.106247Z","end":"2026-07-02T05:10:15.278247Z","steps":["trace[1179411679] 'process raft request' (duration: 140.524033ms)","trace[1179411679] 'compare' (duration: 31.13318ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:15.278454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.611096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-07-02T05:10:15.278581Z","caller":"traceutil/trace.go:171","msg":"trace[1050124073] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:222; }","duration":"146.77197ms","start":"2026-07-02T05:10:15.131787Z","end":"2026-07-02T05:10:15.278559Z","steps":["trace[1050124073] 'agreement among raft nodes before linearized reading' (duration: 146.537744ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:21.000923Z","caller":"traceutil/trace.go:171","msg":"trace[1279978098] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"445.55887ms","start":"2026-07-02T05:10:20.555328Z","end":"2026-07-02T05:10:21.000886Z","steps":["trace[1279978098] 'process raft request' (duration: 445.327964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:21.002816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:20.555287Z","time spent":"446.895205ms","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:10:22.170638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028796998s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-02T05:10:22.170757Z","caller":"traceutil/trace.go:171","msg":"trace[28152775] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:360; }","duration":"1.256704079s","start":"2026-07-02T05:10:20.914034Z","end":"2026-07-02T05:10:22.170738Z","steps":["trace[28152775] 'read index received' (duration: 86.962917ms)","trace[28152775] 'applied index is now lower than readState.Index' (duration: 1.169739452s)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:22.170846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:20.759636Z","time spent":"1.411205956s","remote":"127.0.0.1:52908","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-02T05:10:22.171071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257033949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-gqgv7\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-07-02T05:10:22.171102Z","caller":"traceutil/trace.go:171","msg":"trace[1315249608] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-gqgv7; range_end:; response_count:1; response_revision:350; }","duration":"1.257106481s","start":"2026-07-02T05:10:20.913986Z","end":"2026-07-02T05:10:22.171093Z","steps":["trace[1315249608] 'agreement among raft nodes before linearized reading' (duration: 1.257021069s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:22.17113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:20.913967Z","time spent":"1.257155922s","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4485,"request content":"key:\"/registry/pods/kube-system/kube-proxy-gqgv7\" "} {"level":"warn","ts":"2026-07-02T05:10:22.171291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102211654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-02T05:10:22.171319Z","caller":"traceutil/trace.go:171","msg":"trace[1658146472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:350; }","duration":"1.102276866s","start":"2026-07-02T05:10:21.069034Z","end":"2026-07-02T05:10:22.171311Z","steps":["trace[1658146472] 'agreement among raft nodes before linearized reading' (duration: 1.102226464s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:22.171344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:21.069013Z","time spent":"1.102324436s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T05:10:22.171597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.59773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:10:22.171627Z","caller":"traceutil/trace.go:171","msg":"trace[1914850382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:350; }","duration":"353.628351ms","start":"2026-07-02T05:10:21.817991Z","end":"2026-07-02T05:10:22.171619Z","steps":["trace[1914850382] 'agreement among raft nodes before linearized reading' (duration: 353.57611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:22.171656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:21.817917Z","time spent":"353.732864ms","remote":"127.0.0.1:52654","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":"info","ts":"2026-07-02T05:10:22.676278Z","caller":"traceutil/trace.go:171","msg":"trace[1870780654] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"499.770644ms","start":"2026-07-02T05:10:22.176475Z","end":"2026-07-02T05:10:22.676246Z","steps":["trace[1870780654] 'read index received' (duration: 421.423396ms)","trace[1870780654] 'applied index is now lower than readState.Index' (duration: 78.345817ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:22.676451Z","caller":"traceutil/trace.go:171","msg":"trace[2086404321] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"502.25545ms","start":"2026-07-02T05:10:22.174157Z","end":"2026-07-02T05:10:22.676412Z","steps":["trace[2086404321] 'process raft request' (duration: 423.723708ms)","trace[2086404321] 'compare' (duration: 78.191263ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:22.676626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:22.174122Z","time spent":"502.423934ms","remote":"127.0.0.1:52908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:10:22.67666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.149053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-02T05:10:22.676753Z","caller":"traceutil/trace.go:171","msg":"trace[1239129942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:351; }","duration":"500.401531ms","start":"2026-07-02T05:10:22.176336Z","end":"2026-07-02T05:10:22.676737Z","steps":["trace[1239129942] 'agreement among raft nodes before linearized reading' (duration: 500.039781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:22.676852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:22.176307Z","time spent":"500.531113ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T05:10:23.830001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514556411339564,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:10:24.235285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.017122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:10:24.235756Z","caller":"traceutil/trace.go:171","msg":"trace[857209152] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"2.058962362s","start":"2026-07-02T05:10:22.176734Z","end":"2026-07-02T05:10:24.235697Z","steps":["trace[857209152] 'process raft request' (duration: 1.08220777s)","trace[857209152] 'compare' (duration: 975.714434ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:24.235936Z","caller":"traceutil/trace.go:171","msg":"trace[1868796317] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:362; }","duration":"906.433968ms","start":"2026-07-02T05:10:23.329477Z","end":"2026-07-02T05:10:24.235911Z","steps":["trace[1868796317] 'read index received' (duration: 902.163525ms)","trace[1868796317] 'applied index is now lower than readState.Index' (duration: 4.268583ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:24.236034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:22.176716Z","time spent":"2.059174967s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:24.236212Z","caller":"traceutil/trace.go:171","msg":"trace[305635027] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"2.05927654s","start":"2026-07-02T05:10:22.176925Z","end":"2026-07-02T05:10:24.236202Z","steps":["trace[305635027] 'process raft request' (duration: 2.058605182s)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:24.236251Z","caller":"traceutil/trace.go:171","msg":"trace[600757633] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"1.641327966s","start":"2026-07-02T05:10:22.59491Z","end":"2026-07-02T05:10:24.236238Z","steps":["trace[600757633] 'process raft request' (duration: 1.640895474s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:24.236316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:22.594868Z","time spent":"1.641409759s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:10:24.236314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:22.176912Z","time spent":"2.059347492s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:24.236478Z","caller":"traceutil/trace.go:171","msg":"trace[178884522] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"2.058769726s","start":"2026-07-02T05:10:22.177697Z","end":"2026-07-02T05:10:24.236467Z","steps":["trace[178884522] 'process raft request' (duration: 2.057901593s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:24.236546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:22.177683Z","time spent":"2.058832748s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:24.236636Z","caller":"traceutil/trace.go:171","msg":"trace[1428240181] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"1.55324314s","start":"2026-07-02T05:10:22.683368Z","end":"2026-07-02T05:10:24.236611Z","steps":["trace[1428240181] 'process raft request' (duration: 1.5524905s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:24.236563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.041354ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:10:24.23677Z","caller":"traceutil/trace.go:171","msg":"trace[1883363752] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:357; }","duration":"907.27843ms","start":"2026-07-02T05:10:23.329464Z","end":"2026-07-02T05:10:24.236743Z","steps":["trace[1883363752] 'agreement among raft nodes before linearized reading' (duration: 906.993173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:24.236785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.231665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-02T05:10:24.2368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:22.683328Z","time spent":"1.553380234s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:24.236825Z","caller":"traceutil/trace.go:171","msg":"trace[157315441] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:357; }","duration":"539.325478ms","start":"2026-07-02T05:10:23.697486Z","end":"2026-07-02T05:10:24.236812Z","steps":["trace[157315441] 'agreement among raft nodes before linearized reading' (duration: 539.251236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:24.236861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:23.697455Z","time spent":"539.39847ms","remote":"127.0.0.1:52394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"info","ts":"2026-07-02T05:10:24.23692Z","caller":"traceutil/trace.go:171","msg":"trace[121488392] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"2.057253156s","start":"2026-07-02T05:10:22.179655Z","end":"2026-07-02T05:10:24.236908Z","steps":["trace[121488392] 'process raft request' (duration: 2.056091915s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:24.237033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:22.179631Z","time spent":"2.057367139s","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:49.539266Z","caller":"traceutil/trace.go:171","msg":"trace[1622445116] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"555.16905ms","start":"2026-07-02T05:10:48.984053Z","end":"2026-07-02T05:10:49.539222Z","steps":["trace[1622445116] 'process raft request' (duration: 554.772599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:49.539724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:48.984023Z","time spent":"555.460218ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:10:49.547507Z","caller":"traceutil/trace.go:171","msg":"trace[1647134284] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"549.447908ms","start":"2026-07-02T05:10:48.998015Z","end":"2026-07-02T05:10:49.547463Z","steps":["trace[1647134284] 'process raft request' (duration: 548.79514ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:49.547518Z","caller":"traceutil/trace.go:171","msg":"trace[545411615] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"561.287413ms","start":"2026-07-02T05:10:48.986192Z","end":"2026-07-02T05:10:49.547479Z","steps":["trace[545411615] 'process raft request' (duration: 560.511742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:49.547709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:48.998008Z","time spent":"549.604773ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:10:49.547836Z","caller":"traceutil/trace.go:171","msg":"trace[1738558983] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"561.824677ms","start":"2026-07-02T05:10:48.986004Z","end":"2026-07-02T05:10:49.547829Z","steps":["trace[1738558983] 'process raft request' (duration: 560.656106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:49.54788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:48.985976Z","time spent":"561.886069ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:10:49.54788Z","caller":"traceutil/trace.go:171","msg":"trace[961219644] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"556.28808ms","start":"2026-07-02T05:10:48.99158Z","end":"2026-07-02T05:10:49.547868Z","steps":["trace[961219644] 'process raft request' (duration: 555.15808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:49.547934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:48.991553Z","time spent":"556.355602ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:10:49.547994Z","caller":"traceutil/trace.go:171","msg":"trace[1264318449] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"534.354355ms","start":"2026-07-02T05:10:49.013633Z","end":"2026-07-02T05:10:49.547987Z","steps":["trace[1264318449] 'process raft request' (duration: 533.321138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:49.548025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:49.013619Z","time spent":"534.391747ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:10:49.548024Z","caller":"traceutil/trace.go:171","msg":"trace[1970562736] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"552.266443ms","start":"2026-07-02T05:10:48.995747Z","end":"2026-07-02T05:10:49.548014Z","steps":["trace[1970562736] 'process raft request' (duration: 551.02705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:49.548066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:48.995732Z","time spent":"552.315024ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:10:49.548087Z","caller":"traceutil/trace.go:171","msg":"trace[510801172] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"543.91519ms","start":"2026-07-02T05:10:49.004164Z","end":"2026-07-02T05:10:49.54808Z","steps":["trace[510801172] 'process raft request' (duration: 542.695147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:49.548126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:49.004135Z","time spent":"543.974122ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:10:49.54822Z","caller":"traceutil/trace.go:171","msg":"trace[1734923266] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"497.273757ms","start":"2026-07-02T05:10:49.050937Z","end":"2026-07-02T05:10:49.548211Z","steps":["trace[1734923266] 'process raft request' (duration: 496.112837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:49.548263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:49.0509Z","time spent":"497.341539ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:10:49.548214Z","caller":"traceutil/trace.go:171","msg":"trace[1039283782] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"543.916091ms","start":"2026-07-02T05:10:49.004264Z","end":"2026-07-02T05:10:49.54818Z","steps":["trace[1039283782] 'process raft request' (duration: 542.632216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:49.548387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:49.004257Z","time spent":"544.049344ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:10:49.548504Z","caller":"traceutil/trace.go:171","msg":"trace[505019214] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"532.825455ms","start":"2026-07-02T05:10:49.015665Z","end":"2026-07-02T05:10:49.54849Z","steps":["trace[505019214] 'process raft request' (duration: 531.334555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:49.547718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:48.986186Z","time spent":"561.442787ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:10:49.548558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:49.015653Z","time spent":"532.886516ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:10:49.548652Z","caller":"traceutil/trace.go:171","msg":"trace[1372653215] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"384.836313ms","start":"2026-07-02T05:10:49.163802Z","end":"2026-07-02T05:10:49.548638Z","steps":["trace[1372653215] 'process raft request' (duration: 383.314302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:49.548798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:49.163777Z","time spent":"384.919044ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:10:49.556959Z","caller":"traceutil/trace.go:171","msg":"trace[2065503657] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:500; }","duration":"333.960767ms","start":"2026-07-02T05:10:49.222979Z","end":"2026-07-02T05:10:49.556939Z","steps":["trace[2065503657] 'read index received' (duration: 315.918606ms)","trace[2065503657] 'applied index is now lower than readState.Index' (duration: 18.041231ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:49.557063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.073891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:10:49.557166Z","caller":"traceutil/trace.go:171","msg":"trace[1717275530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"334.230445ms","start":"2026-07-02T05:10:49.222927Z","end":"2026-07-02T05:10:49.557157Z","steps":["trace[1717275530] 'agreement among raft nodes before linearized reading' (duration: 334.084051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:49.557253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:49.222914Z","time spent":"334.331267ms","remote":"127.0.0.1:52362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:10:54.650434Z","caller":"traceutil/trace.go:171","msg":"trace[1377164146] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"249.996887ms","start":"2026-07-02T05:10:54.400411Z","end":"2026-07-02T05:10:54.650408Z","steps":["trace[1377164146] 'read index received' (duration: 249.566395ms)","trace[1377164146] 'applied index is now lower than readState.Index' (duration: 429.152µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:54.650519Z","caller":"traceutil/trace.go:171","msg":"trace[633686249] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"350.476438ms","start":"2026-07-02T05:10:54.299996Z","end":"2026-07-02T05:10:54.650473Z","steps":["trace[633686249] 'process raft request' (duration: 350.121269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:54.650712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:54.29996Z","time spent":"350.658682ms","remote":"127.0.0.1:52718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:10:54.65082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.410597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:10:54.650911Z","caller":"traceutil/trace.go:171","msg":"trace[123098816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"250.594252ms","start":"2026-07-02T05:10:54.400296Z","end":"2026-07-02T05:10:54.65089Z","steps":["trace[123098816] 'agreement among raft nodes before linearized reading' (duration: 250.305525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:54.651136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.423189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-02T05:10:54.651179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.323266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-02T05:10:54.651231Z","caller":"traceutil/trace.go:171","msg":"trace[242063891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"242.553593ms","start":"2026-07-02T05:10:54.40863Z","end":"2026-07-02T05:10:54.651184Z","steps":["trace[242063891] 'agreement among raft nodes before linearized reading' (duration: 242.310626ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:54.651308Z","caller":"traceutil/trace.go:171","msg":"trace[1878288025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"242.491681ms","start":"2026-07-02T05:10:54.408785Z","end":"2026-07-02T05:10:54.651277Z","steps":["trace[1878288025] 'agreement among raft nodes before linearized reading' (duration: 242.032989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:54.652112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.788346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T05:10:54.652167Z","caller":"traceutil/trace.go:171","msg":"trace[1287620302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"245.9016ms","start":"2026-07-02T05:10:54.406252Z","end":"2026-07-02T05:10:54.652154Z","steps":["trace[1287620302] 'agreement among raft nodes before linearized reading' (duration: 244.391371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:55.163226Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514556411340011,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T05:10:55.288289Z","caller":"traceutil/trace.go:171","msg":"trace[1705633702] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"630.98859ms","start":"2026-07-02T05:10:54.657229Z","end":"2026-07-02T05:10:55.288218Z","steps":["trace[1705633702] 'process raft request' (duration: 538.160427ms)","trace[1705633702] 'compare' (duration: 92.471643ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:55.288563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:54.657207Z","time spent":"631.215066ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:55.3088Z","caller":"traceutil/trace.go:171","msg":"trace[1804427723] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"651.435941ms","start":"2026-07-02T05:10:54.657345Z","end":"2026-07-02T05:10:55.30878Z","steps":["trace[1804427723] 'process raft request' (duration: 651.221435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:55.308908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:54.657321Z","time spent":"651.537204ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:55.31062Z","caller":"traceutil/trace.go:171","msg":"trace[1557901047] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:600; }","duration":"647.589141ms","start":"2026-07-02T05:10:54.66296Z","end":"2026-07-02T05:10:55.310549Z","steps":["trace[1557901047] 'read index received' (duration: 532.489629ms)","trace[1557901047] 'applied index is now lower than readState.Index' (duration: 114.863786ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:55.310897Z","caller":"traceutil/trace.go:171","msg":"trace[816433557] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"652.980262ms","start":"2026-07-02T05:10:54.657407Z","end":"2026-07-02T05:10:55.310387Z","steps":["trace[816433557] 'process raft request' (duration: 651.307158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:55.311113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.142736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:10:55.311214Z","caller":"traceutil/trace.go:171","msg":"trace[273779412] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:582; }","duration":"648.240078ms","start":"2026-07-02T05:10:54.662927Z","end":"2026-07-02T05:10:55.311167Z","steps":["trace[273779412] 'agreement among raft nodes before linearized reading' (duration: 647.93751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:55.311266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:54.662915Z","time spent":"648.33922ms","remote":"127.0.0.1:52720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"warn","ts":"2026-07-02T05:10:55.3114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:54.6574Z","time spent":"653.69793ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:55.311401Z","caller":"traceutil/trace.go:171","msg":"trace[1812938951] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"653.936777ms","start":"2026-07-02T05:10:54.657408Z","end":"2026-07-02T05:10:55.311344Z","steps":["trace[1812938951] 'process raft request' (duration: 651.615566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:55.311684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:54.657356Z","time spent":"654.200223ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:10:55.33638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.030177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:10:55.336474Z","caller":"traceutil/trace.go:171","msg":"trace[18154833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"113.177841ms","start":"2026-07-02T05:10:55.223274Z","end":"2026-07-02T05:10:55.336452Z","steps":["trace[18154833] 'agreement among raft nodes before linearized reading' (duration: 113.005437ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:55.901005Z","caller":"traceutil/trace.go:171","msg":"trace[1175038567] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"200.86216ms","start":"2026-07-02T05:10:55.700118Z","end":"2026-07-02T05:10:55.90098Z","steps":["trace[1175038567] 'process raft request' (duration: 200.233844ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:56.874541Z","caller":"traceutil/trace.go:171","msg":"trace[785448958] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"473.737152ms","start":"2026-07-02T05:10:56.40076Z","end":"2026-07-02T05:10:56.874497Z","steps":["trace[785448958] 'process raft request' (duration: 472.88414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:56.874918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:56.400712Z","time spent":"474.070131ms","remote":"127.0.0.1:52470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:56.91193Z","caller":"traceutil/trace.go:171","msg":"trace[317770953] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"506.500064ms","start":"2026-07-02T05:10:56.405392Z","end":"2026-07-02T05:10:56.911892Z","steps":["trace[317770953] 'process raft request' (duration: 506.255608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:56.912158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:56.405349Z","time spent":"506.689819ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:10:57.724027Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514556411340032,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:10:58.225065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514556411340032,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:10:58.726309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514556411340032,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:10:59.156405Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.102791261s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T05:10:59.158746Z","caller":"traceutil/trace.go:171","msg":"trace[211022451] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"2.105267675s","start":"2026-07-02T05:10:57.053421Z","end":"2026-07-02T05:10:59.158689Z","steps":["trace[211022451] 'process raft request' (duration: 2.103703694s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:59.159196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:57.053394Z","time spent":"2.105673546s","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:59.20588Z","caller":"traceutil/trace.go:171","msg":"trace[1425764591] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.772714433s","start":"2026-07-02T05:10:57.433008Z","end":"2026-07-02T05:10:59.205723Z","steps":["trace[1425764591] 'process raft request' (duration: 1.771336737s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:59.20626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:57.432959Z","time spent":"1.77302488s","remote":"127.0.0.1:52470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:10:59.222025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999056695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-02T05:10:59.222083Z","caller":"traceutil/trace.go:171","msg":"trace[786138002] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999166027s","start":"2026-07-02T05:10:57.2229Z","end":"2026-07-02T05:10:59.222066Z","steps":["trace[786138002] 'agreement among raft nodes before linearized reading' (duration: 1.999096655s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:59.222206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:57.222874Z","time spent":"1.999319322s","remote":"127.0.0.1:52362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/02 05:10:59 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-07-02T05:10:59.248541Z","caller":"traceutil/trace.go:171","msg":"trace[467730469] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:613; }","duration":"2.025550053s","start":"2026-07-02T05:10:57.222956Z","end":"2026-07-02T05:10:59.248506Z","steps":["trace[467730469] 'read index received' (duration: 1.934269311s)","trace[467730469] 'applied index is now lower than readState.Index' (duration: 91.279092ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:59.249038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.955741309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T05:10:59.249076Z","caller":"traceutil/trace.go:171","msg":"trace[1431480532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"1.955863162s","start":"2026-07-02T05:10:57.293202Z","end":"2026-07-02T05:10:59.249066Z","steps":["trace[1431480532] 'agreement among raft nodes before linearized reading' (duration: 1.955662477s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:59.249115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:57.293172Z","time spent":"1.955934984s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T05:10:59.257329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.93845424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-02T05:10:59.257452Z","caller":"traceutil/trace.go:171","msg":"trace[736631296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"1.938600653s","start":"2026-07-02T05:10:57.318823Z","end":"2026-07-02T05:10:59.257423Z","steps":["trace[736631296] 'agreement among raft nodes before linearized reading' (duration: 1.936287603s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:59.257531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:57.318817Z","time spent":"1.938701627s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:10:59.258566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.937640888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T05:10:59.258816Z","caller":"traceutil/trace.go:171","msg":"trace[37647462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"1.939863727s","start":"2026-07-02T05:10:57.318933Z","end":"2026-07-02T05:10:59.258797Z","steps":["trace[37647462] 'agreement among raft nodes before linearized reading' (duration: 1.936011037s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:59.258794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.939959189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-02T05:10:59.258856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:57.318927Z","time spent":"1.939918558s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T05:10:59.258883Z","caller":"traceutil/trace.go:171","msg":"trace[1277423182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"1.940080252s","start":"2026-07-02T05:10:57.318779Z","end":"2026-07-02T05:10:59.25886Z","steps":["trace[1277423182] 'agreement among raft nodes before linearized reading' (duration: 1.936259423s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:59.259343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:57.318766Z","time spent":"1.940557694s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T05:11:01.422377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.577045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:11:01.42255Z","caller":"traceutil/trace.go:171","msg":"trace[69161490] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"200.400949ms","start":"2026-07-02T05:11:01.222124Z","end":"2026-07-02T05:11:01.422525Z","steps":["trace[69161490] 'read index received' (duration: 112.633µs)","trace[69161490] 'applied index is now lower than readState.Index' (duration: 200.286566ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:11:01.422649Z","caller":"traceutil/trace.go:171","msg":"trace[143639931] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"238.327154ms","start":"2026-07-02T05:11:01.184273Z","end":"2026-07-02T05:11:01.4226Z","steps":["trace[143639931] 'process raft request' (duration: 30.378289ms)","trace[143639931] 'compare' (duration: 207.399211ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:11:01.422764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.600374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:11:01.423032Z","caller":"traceutil/trace.go:171","msg":"trace[1559645120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"200.973543ms","start":"2026-07-02T05:11:01.222016Z","end":"2026-07-02T05:11:01.422989Z","steps":["trace[1559645120] 'agreement among raft nodes before linearized reading' (duration: 200.616894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:01.443708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.703648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-02T05:11:01.443755Z","caller":"traceutil/trace.go:171","msg":"trace[1130843636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"172.800061ms","start":"2026-07-02T05:11:01.27094Z","end":"2026-07-02T05:11:01.44374Z","steps":["trace[1130843636] 'agreement among raft nodes before linearized reading' (duration: 172.601376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:01.443729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.020929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:11:01.443763Z","caller":"traceutil/trace.go:171","msg":"trace[501380087] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"207.39679ms","start":"2026-07-02T05:11:01.23634Z","end":"2026-07-02T05:11:01.443736Z","steps":["trace[501380087] 'process raft request' (duration: 207.01057ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:01.44383Z","caller":"traceutil/trace.go:171","msg":"trace[251924749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"170.175083ms","start":"2026-07-02T05:11:01.273633Z","end":"2026-07-02T05:11:01.443808Z","steps":["trace[251924749] 'agreement among raft nodes before linearized reading' (duration: 169.891846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:01.443889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.101191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T05:11:01.443957Z","caller":"traceutil/trace.go:171","msg":"trace[1801596092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"175.177233ms","start":"2026-07-02T05:11:01.268763Z","end":"2026-07-02T05:11:01.443941Z","steps":["trace[1801596092] 'agreement among raft nodes before linearized reading' (duration: 174.992328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:01.444016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.332527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T05:11:01.444075Z","caller":"traceutil/trace.go:171","msg":"trace[634059916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"140.4341ms","start":"2026-07-02T05:11:01.303625Z","end":"2026-07-02T05:11:01.444059Z","steps":["trace[634059916] 'agreement among raft nodes before linearized reading' (duration: 140.199244ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:04.62765Z","caller":"traceutil/trace.go:171","msg":"trace[797675551] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"229.739051ms","start":"2026-07-02T05:11:04.397889Z","end":"2026-07-02T05:11:04.627628Z","steps":["trace[797675551] 'process raft request' (duration: 229.547956ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:04.664028Z","caller":"traceutil/trace.go:171","msg":"trace[1805961393] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"192.36151ms","start":"2026-07-02T05:11:04.471649Z","end":"2026-07-02T05:11:04.66401Z","steps":["trace[1805961393] 'process raft request' (duration: 191.939879ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:12.308881Z","caller":"traceutil/trace.go:171","msg":"trace[797926150] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"126.641592ms","start":"2026-07-02T05:11:12.182187Z","end":"2026-07-02T05:11:12.308829Z","steps":["trace[797926150] 'process raft request' (duration: 126.494928ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:12.309129Z","caller":"traceutil/trace.go:171","msg":"trace[829632601] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"128.397327ms","start":"2026-07-02T05:11:12.180711Z","end":"2026-07-02T05:11:12.309109Z","steps":["trace[829632601] 'process raft request' (duration: 127.73237ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:12.817069Z","caller":"traceutil/trace.go:171","msg":"trace[1721726423] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"499.648155ms","start":"2026-07-02T05:11:12.317357Z","end":"2026-07-02T05:11:12.817005Z","steps":["trace[1721726423] 'process raft request' (duration: 499.336327ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:12.817142Z","caller":"traceutil/trace.go:171","msg":"trace[61278444] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"499.580804ms","start":"2026-07-02T05:11:12.317515Z","end":"2026-07-02T05:11:12.817096Z","steps":["trace[61278444] 'process raft request' (duration: 499.372088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:12.817362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:12.31747Z","time spent":"499.765928ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:12.817377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:12.317343Z","time spent":"499.85388ms","remote":"127.0.0.1:52534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:11:12.845672Z","caller":"traceutil/trace.go:171","msg":"trace[1087997394] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"475.016584ms","start":"2026-07-02T05:11:12.370615Z","end":"2026-07-02T05:11:12.845632Z","steps":["trace[1087997394] 'process raft request' (duration: 474.795209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:12.846056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:12.370589Z","time spent":"475.192299ms","remote":"127.0.0.1:52846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:15.794915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.947834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:11:15.795043Z","caller":"traceutil/trace.go:171","msg":"trace[1113984838] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:759; }","duration":"465.15877ms","start":"2026-07-02T05:11:15.329851Z","end":"2026-07-02T05:11:15.795009Z","steps":["trace[1113984838] 'count revisions from in-memory index tree' (duration: 464.867022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:15.795103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:15.32983Z","time spent":"465.257032ms","remote":"127.0.0.1:52872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:11:15.795703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.278485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:11:15.795864Z","caller":"traceutil/trace.go:171","msg":"trace[1724855293] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"254.767261ms","start":"2026-07-02T05:11:15.541074Z","end":"2026-07-02T05:11:15.795841Z","steps":["trace[1724855293] 'read index received' (duration: 57.341µs)","trace[1724855293] 'applied index is now lower than readState.Index' (duration: 254.70828ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:11:15.795949Z","caller":"traceutil/trace.go:171","msg":"trace[90881808] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"406.722681ms","start":"2026-07-02T05:11:15.389207Z","end":"2026-07-02T05:11:15.795929Z","steps":["trace[90881808] 'process raft request' (duration: 146.050346ms)","trace[90881808] 'compare' (duration: 259.800562ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:11:15.796089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:15.389182Z","time spent":"406.815173ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:15.796281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.693257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-02T05:11:15.796362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.855954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-02T05:11:15.796426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.940401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-02T05:11:15.796535Z","caller":"traceutil/trace.go:171","msg":"trace[637791870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"254.082444ms","start":"2026-07-02T05:11:15.542426Z","end":"2026-07-02T05:11:15.796508Z","steps":["trace[637791870] 'agreement among raft nodes before linearized reading' (duration: 253.716384ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:15.796563Z","caller":"traceutil/trace.go:171","msg":"trace[742564473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"255.139522ms","start":"2026-07-02T05:11:15.541409Z","end":"2026-07-02T05:11:15.796548Z","steps":["trace[742564473] 'agreement among raft nodes before linearized reading' (duration: 254.668609ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:15.796378Z","caller":"traceutil/trace.go:171","msg":"trace[1724833773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"252.808531ms","start":"2026-07-02T05:11:15.543552Z","end":"2026-07-02T05:11:15.79636Z","steps":["trace[1724833773] 'agreement among raft nodes before linearized reading' (duration: 252.532234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:15.797225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.139818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:11:15.797297Z","caller":"traceutil/trace.go:171","msg":"trace[1256044032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"256.278401ms","start":"2026-07-02T05:11:15.541002Z","end":"2026-07-02T05:11:15.797281Z","steps":["trace[1256044032] 'agreement among raft nodes before linearized reading' (duration: 254.992587ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:16.216942Z","caller":"traceutil/trace.go:171","msg":"trace[1922110623] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"415.506988ms","start":"2026-07-02T05:11:15.801405Z","end":"2026-07-02T05:11:16.216912Z","steps":["trace[1922110623] 'process raft request' (duration: 407.295155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:16.217149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:15.801379Z","time spent":"415.655342ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:11:16.218643Z","caller":"traceutil/trace.go:171","msg":"trace[1254430900] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"416.913354ms","start":"2026-07-02T05:11:15.801706Z","end":"2026-07-02T05:11:16.21862Z","steps":["trace[1254430900] 'process raft request' (duration: 416.628877ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:16.218652Z","caller":"traceutil/trace.go:171","msg":"trace[601363852] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"416.697939ms","start":"2026-07-02T05:11:15.80192Z","end":"2026-07-02T05:11:16.218618Z","steps":["trace[601363852] 'process raft request' (duration: 416.586957ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:16.218891Z","caller":"traceutil/trace.go:171","msg":"trace[2141222957] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"416.954946ms","start":"2026-07-02T05:11:15.801924Z","end":"2026-07-02T05:11:16.218879Z","steps":["trace[2141222957] 'process raft request' (duration: 416.624178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:16.218745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:15.801673Z","time spent":"417.021707ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:16.219144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:15.801908Z","time spent":"417.164901ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:11:16.219547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:15.801909Z","time spent":"417.156341ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:11:19.269889Z","caller":"traceutil/trace.go:171","msg":"trace[1145300669] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"102.079323ms","start":"2026-07-02T05:11:19.167772Z","end":"2026-07-02T05:11:19.269851Z","steps":["trace[1145300669] 'process raft request' (duration: 101.688823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:20.478047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.804956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:11:20.478316Z","caller":"traceutil/trace.go:171","msg":"trace[482305990] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"235.998433ms","start":"2026-07-02T05:11:20.2423Z","end":"2026-07-02T05:11:20.478299Z","steps":["trace[482305990] 'process raft request' (duration: 235.942982ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:20.478682Z","caller":"traceutil/trace.go:171","msg":"trace[2003572319] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"237.202715ms","start":"2026-07-02T05:11:20.24143Z","end":"2026-07-02T05:11:20.478633Z","steps":["trace[2003572319] 'process raft request' (duration: 114.724132ms)","trace[2003572319] 'compare' (duration: 121.681602ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:11:20.478698Z","caller":"traceutil/trace.go:171","msg":"trace[367573243] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"237.149333ms","start":"2026-07-02T05:11:20.241494Z","end":"2026-07-02T05:11:20.478643Z","steps":["trace[367573243] 'process raft request' (duration: 236.653881ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:20.536722Z","caller":"traceutil/trace.go:171","msg":"trace[840428950] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"136.605961ms","start":"2026-07-02T05:11:20.400088Z","end":"2026-07-02T05:11:20.536694Z","steps":["trace[840428950] 'read index received' (duration: 136.395285ms)","trace[840428950] 'applied index is now lower than readState.Index' (duration: 209.596µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:11:20.536748Z","caller":"traceutil/trace.go:171","msg":"trace[21378874] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"252.964975ms","start":"2026-07-02T05:11:20.283758Z","end":"2026-07-02T05:11:20.536723Z","steps":["trace[21378874] 'process raft request' (duration: 252.762289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:20.536862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.758085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:11:20.537063Z","caller":"traceutil/trace.go:171","msg":"trace[1044868860] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:782; }","duration":"136.862007ms","start":"2026-07-02T05:11:20.400033Z","end":"2026-07-02T05:11:20.536895Z","steps":["trace[1044868860] 'agreement among raft nodes before linearized reading' (duration: 136.769125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:20.537074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.843357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:11:20.537204Z","caller":"traceutil/trace.go:171","msg":"trace[659536280] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:782; }","duration":"137.023661ms","start":"2026-07-02T05:11:20.400144Z","end":"2026-07-02T05:11:20.537167Z","steps":["trace[659536280] 'agreement among raft nodes before linearized reading' (duration: 136.787875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:20.537528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.280866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:11:20.537571Z","caller":"traceutil/trace.go:171","msg":"trace[33295539] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:782; }","duration":"135.346887ms","start":"2026-07-02T05:11:20.402213Z","end":"2026-07-02T05:11:20.53756Z","steps":["trace[33295539] 'agreement among raft nodes before linearized reading' (duration: 135.270255ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:20.676836Z","caller":"traceutil/trace.go:171","msg":"trace[1566759115] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"116.747245ms","start":"2026-07-02T05:11:20.560065Z","end":"2026-07-02T05:11:20.676812Z","steps":["trace[1566759115] 'read index received' (duration: 116.507908ms)","trace[1566759115] 'applied index is now lower than readState.Index' (duration: 235.877µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:11:20.677085Z","caller":"traceutil/trace.go:171","msg":"trace[1661614226] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"129.965927ms","start":"2026-07-02T05:11:20.547107Z","end":"2026-07-02T05:11:20.677073Z","steps":["trace[1661614226] 'process raft request' (duration: 129.534686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:20.677455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.281798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:11:20.677706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.507208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:11:20.677823Z","caller":"traceutil/trace.go:171","msg":"trace[59170123] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:783; }","duration":"116.667182ms","start":"2026-07-02T05:11:20.56113Z","end":"2026-07-02T05:11:20.677797Z","steps":["trace[59170123] 'agreement among raft nodes before linearized reading' (duration: 116.495608ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:20.678245Z","caller":"traceutil/trace.go:171","msg":"trace[1439711648] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:783; }","duration":"117.447493ms","start":"2026-07-02T05:11:20.560094Z","end":"2026-07-02T05:11:20.677541Z","steps":["trace[1439711648] 'agreement among raft nodes before linearized reading' (duration: 117.226327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:20.678493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.402228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:11:20.678571Z","caller":"traceutil/trace.go:171","msg":"trace[269758031] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:783; }","duration":"118.49625ms","start":"2026-07-02T05:11:20.560058Z","end":"2026-07-02T05:11:20.678554Z","steps":["trace[269758031] 'agreement among raft nodes before linearized reading' (duration: 118.071919ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:25.366643Z","caller":"traceutil/trace.go:171","msg":"trace[1442138423] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"117.321259ms","start":"2026-07-02T05:11:25.249275Z","end":"2026-07-02T05:11:25.366597Z","steps":["trace[1442138423] 'process raft request' (duration: 84.67751ms)","trace[1442138423] 'compare' (duration: 32.400943ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:11:25.367463Z","caller":"traceutil/trace.go:171","msg":"trace[1593841556] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"103.558462ms","start":"2026-07-02T05:11:25.263827Z","end":"2026-07-02T05:11:25.367385Z","steps":["trace[1593841556] 'process raft request' (duration: 103.221253ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:25.870108Z","caller":"traceutil/trace.go:171","msg":"trace[782561408] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"118.763297ms","start":"2026-07-02T05:11:25.751319Z","end":"2026-07-02T05:11:25.870082Z","steps":["trace[782561408] 'process raft request' (duration: 118.612503ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:30.736477Z","caller":"traceutil/trace.go:171","msg":"trace[934504961] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"102.253267ms","start":"2026-07-02T05:11:30.634194Z","end":"2026-07-02T05:11:30.736447Z","steps":["trace[934504961] 'process raft request' (duration: 101.97294ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:40.977314Z","caller":"traceutil/trace.go:171","msg":"trace[1607432104] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"108.086419ms","start":"2026-07-02T05:11:40.869208Z","end":"2026-07-02T05:11:40.977294Z","steps":["trace[1607432104] 'process raft request' (duration: 107.987667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:05.137212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.791672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:12:05.137307Z","caller":"traceutil/trace.go:171","msg":"trace[1715681111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1024; }","duration":"313.944185ms","start":"2026-07-02T05:12:04.823344Z","end":"2026-07-02T05:12:05.137288Z","steps":["trace[1715681111] 'range keys from in-memory index tree' (duration: 313.585007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:05.137347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:04.823318Z","time spent":"314.018328ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T05:12:05.137526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.137673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-02T05:12:05.137577Z","caller":"traceutil/trace.go:171","msg":"trace[1968299666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1024; }","duration":"140.217525ms","start":"2026-07-02T05:12:04.997348Z","end":"2026-07-02T05:12:05.137566Z","steps":["trace[1968299666] 'range keys from in-memory index tree' (duration: 140.045641ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:07.342139Z","caller":"traceutil/trace.go:171","msg":"trace[1271462274] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"152.84098ms","start":"2026-07-02T05:12:07.189276Z","end":"2026-07-02T05:12:07.342117Z","steps":["trace[1271462274] 'read index received' (duration: 152.798509ms)","trace[1271462274] 'applied index is now lower than readState.Index' (duration: 41.581µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:12:07.342206Z","caller":"traceutil/trace.go:171","msg":"trace[1403155505] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"616.561679ms","start":"2026-07-02T05:12:06.725617Z","end":"2026-07-02T05:12:07.342178Z","steps":["trace[1403155505] 'process raft request' (duration: 616.349543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:07.342317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:06.725589Z","time spent":"616.65247ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:12:07.342367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.091006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-02T05:12:07.342399Z","caller":"traceutil/trace.go:171","msg":"trace[1398737497] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1030; }","duration":"153.161478ms","start":"2026-07-02T05:12:07.189228Z","end":"2026-07-02T05:12:07.342389Z","steps":["trace[1398737497] 'agreement among raft nodes before linearized reading' (duration: 153.011644ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:07.674393Z","caller":"traceutil/trace.go:171","msg":"trace[554402384] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"414.300256ms","start":"2026-07-02T05:12:07.260057Z","end":"2026-07-02T05:12:07.674357Z","steps":["trace[554402384] 'process raft request' (duration: 339.515013ms)","trace[554402384] 'compare' (duration: 74.391534ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:12:07.674502Z","caller":"traceutil/trace.go:171","msg":"trace[1353934095] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"327.342299ms","start":"2026-07-02T05:12:07.347125Z","end":"2026-07-02T05:12:07.674468Z","steps":["trace[1353934095] 'process raft request' (duration: 327.237097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:07.674579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:07.260036Z","time spent":"414.44329ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:12:07.674652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.354215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:12:07.674712Z","caller":"traceutil/trace.go:171","msg":"trace[1380332821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"450.415997ms","start":"2026-07-02T05:12:07.224283Z","end":"2026-07-02T05:12:07.674699Z","steps":["trace[1380332821] 'agreement among raft nodes before linearized reading' (duration: 450.327795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:07.674733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:07.347097Z","time spent":"327.509094ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:12:07.674753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:07.224268Z","time spent":"450.478368ms","remote":"127.0.0.1:52362","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-07-02T05:12:07.674983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.953263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:12:07.675027Z","caller":"traceutil/trace.go:171","msg":"trace[1310685331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1032; }","duration":"476.056716ms","start":"2026-07-02T05:12:07.198959Z","end":"2026-07-02T05:12:07.675016Z","steps":["trace[1310685331] 'agreement among raft nodes before linearized reading' (duration: 475.914732ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:07.674801Z","caller":"traceutil/trace.go:171","msg":"trace[535152483] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"332.254121ms","start":"2026-07-02T05:12:07.342212Z","end":"2026-07-02T05:12:07.674466Z","steps":["trace[535152483] 'read index received' (duration: 257.371875ms)","trace[535152483] 'applied index is now lower than readState.Index' (duration: 74.879416ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:12:07.675063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:07.198934Z","time spent":"476.118058ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T05:12:07.675245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.890836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-02T05:12:07.675284Z","caller":"traceutil/trace.go:171","msg":"trace[111286559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1032; }","duration":"476.930977ms","start":"2026-07-02T05:12:07.198342Z","end":"2026-07-02T05:12:07.675273Z","steps":["trace[111286559] 'agreement among raft nodes before linearized reading' (duration: 476.741083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:07.675319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:07.198281Z","time spent":"477.03085ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T05:12:07.884674Z","caller":"traceutil/trace.go:171","msg":"trace[213308926] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"123.026616ms","start":"2026-07-02T05:12:07.761611Z","end":"2026-07-02T05:12:07.884638Z","steps":["trace[213308926] 'process raft request' (duration: 25.889685ms)","trace[213308926] 'compare' (duration: 96.948597ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:12:08.616679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.37871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:12:08.616795Z","caller":"traceutil/trace.go:171","msg":"trace[1983094952] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"504.339541ms","start":"2026-07-02T05:12:08.112437Z","end":"2026-07-02T05:12:08.616776Z","steps":["trace[1983094952] 'process raft request' (duration: 28.743587ms)","trace[1983094952] 'compare' (duration: 475.260776ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:12:08.616846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:08.112407Z","time spent":"504.415074ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:12:09.723367Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514556411340998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:12:10.099205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.318602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:12:10.099737Z","caller":"traceutil/trace.go:171","msg":"trace[814455092] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"1.299214703s","start":"2026-07-02T05:12:08.800469Z","end":"2026-07-02T05:12:10.099684Z","steps":["trace[814455092] 'process raft request' (duration: 357.251915ms)","trace[814455092] 'compare' (duration: 941.191919ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:12:10.099934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:08.800434Z","time spent":"1.299399918s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:12:10.102449Z","caller":"traceutil/trace.go:171","msg":"trace[1464308371] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1078; }","duration":"879.670446ms","start":"2026-07-02T05:12:09.222761Z","end":"2026-07-02T05:12:10.102431Z","steps":["trace[1464308371] 'read index received' (duration: 870.60807ms)","trace[1464308371] 'applied index is now lower than readState.Index' (duration: 9.059336ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:12:10.102609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.818749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:12:10.102694Z","caller":"traceutil/trace.go:171","msg":"trace[23204371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1037; }","duration":"879.965393ms","start":"2026-07-02T05:12:09.222709Z","end":"2026-07-02T05:12:10.102674Z","steps":["trace[23204371] 'agreement among raft nodes before linearized reading' (duration: 879.809359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:10.102734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:09.222686Z","time spent":"880.041195ms","remote":"127.0.0.1:52362","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-07-02T05:12:10.10272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.06559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-02T05:12:10.102786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.896575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-02T05:12:10.102786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.686856ms","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-07-02T05:12:10.102792Z","caller":"traceutil/trace.go:171","msg":"trace[2016608394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1037; }","duration":"755.192783ms","start":"2026-07-02T05:12:09.347582Z","end":"2026-07-02T05:12:10.102775Z","steps":["trace[2016608394] 'agreement among raft nodes before linearized reading' (duration: 754.999509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:10.1028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.767248ms","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-07-02T05:12:10.102825Z","caller":"traceutil/trace.go:171","msg":"trace[200191178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1037; }","duration":"423.990698ms","start":"2026-07-02T05:12:09.678825Z","end":"2026-07-02T05:12:10.102815Z","steps":["trace[200191178] 'agreement among raft nodes before linearized reading' (duration: 423.827584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:10.102839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:09.347549Z","time spent":"755.282635ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T05:12:10.102829Z","caller":"traceutil/trace.go:171","msg":"trace[1533743955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1037; }","duration":"213.746918ms","start":"2026-07-02T05:12:09.889074Z","end":"2026-07-02T05:12:10.102821Z","steps":["trace[1533743955] 'agreement among raft nodes before linearized reading' (duration: 213.562333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:10.10287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:09.678801Z","time spent":"424.06237ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T05:12:10.102879Z","caller":"traceutil/trace.go:171","msg":"trace[1712868254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1037; }","duration":"355.911251ms","start":"2026-07-02T05:12:09.746947Z","end":"2026-07-02T05:12:10.102858Z","steps":["trace[1712868254] 'agreement among raft nodes before linearized reading' (duration: 355.657725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:10.102918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:09.746932Z","time spent":"355.978693ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T05:12:10.102977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.944083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-07-02T05:12:10.10279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.45726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:12:10.103018Z","caller":"traceutil/trace.go:171","msg":"trace[1359652508] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1037; }","duration":"680.757777ms","start":"2026-07-02T05:12:09.422251Z","end":"2026-07-02T05:12:10.103009Z","steps":["trace[1359652508] 'agreement among raft nodes before linearized reading' (duration: 680.422349ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:10.103019Z","caller":"traceutil/trace.go:171","msg":"trace[773195213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1037; }","duration":"342.010995ms","start":"2026-07-02T05:12:09.760991Z","end":"2026-07-02T05:12:10.103002Z","steps":["trace[773195213] 'agreement among raft nodes before linearized reading' (duration: 341.864631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:10.103048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:09.422221Z","time spent":"680.815559ms","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:12:10.103052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:09.760967Z","time spent":"342.079676ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T05:12:22.354336Z","caller":"traceutil/trace.go:171","msg":"trace[2060494465] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"170.743396ms","start":"2026-07-02T05:12:22.183558Z","end":"2026-07-02T05:12:22.354302Z","steps":["trace[2060494465] 'process raft request' (duration: 170.564932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:22.732518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.512674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:12:22.732658Z","caller":"traceutil/trace.go:171","msg":"trace[629859218] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1121; }","duration":"541.432457ms","start":"2026-07-02T05:12:22.191206Z","end":"2026-07-02T05:12:22.732638Z","steps":["trace[629859218] 'read index received' (duration: 162.918852ms)","trace[629859218] 'applied index is now lower than readState.Index' (duration: 378.512394ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:12:22.732716Z","caller":"traceutil/trace.go:171","msg":"trace[677610441] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"545.118728ms","start":"2026-07-02T05:12:22.187545Z","end":"2026-07-02T05:12:22.732664Z","steps":["trace[677610441] 'process raft request' (duration: 347.352829ms)","trace[677610441] 'compare' (duration: 197.317028ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:12:22.732927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.720073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-02T05:12:22.732973Z","caller":"traceutil/trace.go:171","msg":"trace[1376466638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1078; }","duration":"541.788365ms","start":"2026-07-02T05:12:22.191172Z","end":"2026-07-02T05:12:22.73296Z","steps":["trace[1376466638] 'agreement among raft nodes before linearized reading' (duration: 541.545308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:22.732953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:22.187518Z","time spent":"545.307812ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:12:22.733017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:22.19116Z","time spent":"541.848267ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T05:12:23.138001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.292484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:12:23.138131Z","caller":"traceutil/trace.go:171","msg":"trace[368356114] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"405.421255ms","start":"2026-07-02T05:12:22.732689Z","end":"2026-07-02T05:12:23.13811Z","steps":["trace[368356114] 'read index received' (duration: 220.885166ms)","trace[368356114] 'applied index is now lower than readState.Index' (duration: 184.534499ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:12:23.138322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.434926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-02T05:12:23.138348Z","caller":"traceutil/trace.go:171","msg":"trace[1682442959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1079; }","duration":"945.475787ms","start":"2026-07-02T05:12:22.192862Z","end":"2026-07-02T05:12:23.138338Z","steps":["trace[1682442959] 'agreement among raft nodes before linearized reading' (duration: 945.302873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:23.138384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:22.192855Z","time spent":"945.520968ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:12:23.138439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.253324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:12:23.138568Z","caller":"traceutil/trace.go:171","msg":"trace[77356037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1079; }","duration":"913.421858ms","start":"2026-07-02T05:12:22.225114Z","end":"2026-07-02T05:12:23.138535Z","steps":["trace[77356037] 'agreement among raft nodes before linearized reading' (duration: 913.212933ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:23.138583Z","caller":"traceutil/trace.go:171","msg":"trace[1129545125] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"473.497253ms","start":"2026-07-02T05:12:22.665042Z","end":"2026-07-02T05:12:23.138539Z","steps":["trace[1129545125] 'process raft request' (duration: 288.514852ms)","trace[1129545125] 'compare' (duration: 184.15215ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:12:23.138612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.471252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-02T05:12:23.138647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:22.225106Z","time spent":"913.5261ms","remote":"127.0.0.1:52362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:12:23.138721Z","caller":"traceutil/trace.go:171","msg":"trace[1687520262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1079; }","duration":"938.593796ms","start":"2026-07-02T05:12:22.200097Z","end":"2026-07-02T05:12:23.138691Z","steps":["trace[1687520262] 'agreement among raft nodes before linearized reading' (duration: 938.277418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:23.138782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:22.200042Z","time spent":"938.724089ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T05:12:23.138785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:22.665005Z","time spent":"473.662067ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:12:23.184522Z","caller":"traceutil/trace.go:171","msg":"trace[1524168282] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"445.707859ms","start":"2026-07-02T05:12:22.738782Z","end":"2026-07-02T05:12:23.18449Z","steps":["trace[1524168282] 'process raft request' (duration: 445.449273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:23.184819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:22.73873Z","time spent":"445.886484ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:12:25.423956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.287746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-02T05:12:25.424059Z","caller":"traceutil/trace.go:171","msg":"trace[362169378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1084; }","duration":"686.471051ms","start":"2026-07-02T05:12:24.737564Z","end":"2026-07-02T05:12:25.424035Z","steps":["trace[362169378] 'range keys from in-memory index tree' (duration: 686.080362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:25.424112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:24.73754Z","time spent":"686.555463ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T05:12:25.424546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.651053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:12:25.424694Z","caller":"traceutil/trace.go:171","msg":"trace[1130262511] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"235.330255ms","start":"2026-07-02T05:12:25.189338Z","end":"2026-07-02T05:12:25.424668Z","steps":["trace[1130262511] 'read index received' (duration: 3.300212ms)","trace[1130262511] 'applied index is now lower than readState.Index' (duration: 232.028223ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:12:25.424701Z","caller":"traceutil/trace.go:171","msg":"trace[1826058116] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"279.725182ms","start":"2026-07-02T05:12:25.144954Z","end":"2026-07-02T05:12:25.424679Z","steps":["trace[1826058116] 'process raft request' (duration: 47.810572ms)","trace[1826058116] 'compare' (duration: 231.470989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:12:25.424947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.609083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-02T05:12:25.425004Z","caller":"traceutil/trace.go:171","msg":"trace[1148112] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1085; }","duration":"235.704645ms","start":"2026-07-02T05:12:25.189284Z","end":"2026-07-02T05:12:25.424989Z","steps":["trace[1148112] 'agreement among raft nodes before linearized reading' (duration: 235.49759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:25.425242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.849039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-07-02T05:12:25.425263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.032934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-02T05:12:25.425373Z","caller":"traceutil/trace.go:171","msg":"trace[1183543765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1085; }","duration":"224.175878ms","start":"2026-07-02T05:12:25.201173Z","end":"2026-07-02T05:12:25.425349Z","steps":["trace[1183543765] 'agreement among raft nodes before linearized reading' (duration: 223.874501ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:25.425309Z","caller":"traceutil/trace.go:171","msg":"trace[1897921797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1085; }","duration":"223.969613ms","start":"2026-07-02T05:12:25.201328Z","end":"2026-07-02T05:12:25.425298Z","steps":["trace[1897921797] 'agreement among raft nodes before linearized reading' (duration: 223.801969ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:25.842657Z","caller":"traceutil/trace.go:171","msg":"trace[1456210561] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"412.556583ms","start":"2026-07-02T05:12:25.430063Z","end":"2026-07-02T05:12:25.84262Z","steps":["trace[1456210561] 'process raft request' (duration: 395.090628ms)","trace[1456210561] 'compare' (duration: 16.817949ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:12:25.842976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:25.430042Z","time spent":"412.693937ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:12:25.852251Z","caller":"traceutil/trace.go:171","msg":"trace[570215317] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"420.962582ms","start":"2026-07-02T05:12:25.431259Z","end":"2026-07-02T05:12:25.852221Z","steps":["trace[570215317] 'process raft request' (duration: 420.87793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:25.852448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:25.431239Z","time spent":"421.104476ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:12:25.85251Z","caller":"traceutil/trace.go:171","msg":"trace[2015462727] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"421.430974ms","start":"2026-07-02T05:12:25.431061Z","end":"2026-07-02T05:12:25.852492Z","steps":["trace[2015462727] 'process raft request' (duration: 420.913531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:25.852582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:25.431035Z","time spent":"421.514146ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:12:25.852659Z","caller":"traceutil/trace.go:171","msg":"trace[1262509153] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"421.083185ms","start":"2026-07-02T05:12:25.431546Z","end":"2026-07-02T05:12:25.852629Z","steps":["trace[1262509153] 'process raft request' (duration: 420.633954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:25.852824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:25.431536Z","time spent":"421.203228ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:12:38.70432Z","caller":"traceutil/trace.go:171","msg":"trace[1919272977] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"116.955975ms","start":"2026-07-02T05:12:38.587308Z","end":"2026-07-02T05:12:38.704264Z","steps":["trace[1919272977] 'process raft request' (duration: 116.651428ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:38.70475Z","caller":"traceutil/trace.go:171","msg":"trace[1055257875] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1213; }","duration":"110.70657ms","start":"2026-07-02T05:12:38.594013Z","end":"2026-07-02T05:12:38.704719Z","steps":["trace[1055257875] 'read index received' (duration: 39.674139ms)","trace[1055257875] 'applied index is now lower than readState.Index' (duration: 71.031071ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:12:38.704906Z","caller":"traceutil/trace.go:171","msg":"trace[1916033901] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"113.868258ms","start":"2026-07-02T05:12:38.591026Z","end":"2026-07-02T05:12:38.704894Z","steps":["trace[1916033901] 'process raft request' (duration: 113.452568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:38.705211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.174361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-97p6c\" ","response":"range_response_count:1 size:9695"} {"level":"info","ts":"2026-07-02T05:12:38.705269Z","caller":"traceutil/trace.go:171","msg":"trace[883825125] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-97p6c; range_end:; response_count:1; response_revision:1169; }","duration":"111.298414ms","start":"2026-07-02T05:12:38.593957Z","end":"2026-07-02T05:12:38.705256Z","steps":["trace[883825125] 'agreement among raft nodes before linearized reading' (duration: 111.062258ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:38.705419Z","caller":"traceutil/trace.go:171","msg":"trace[1053327202] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"111.606722ms","start":"2026-07-02T05:12:38.5938Z","end":"2026-07-02T05:12:38.705406Z","steps":["trace[1053327202] 'process raft request' (duration: 110.826662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:38.705492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.205822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:10872"} {"level":"info","ts":"2026-07-02T05:12:38.705751Z","caller":"traceutil/trace.go:171","msg":"trace[238911189] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1169; }","duration":"111.595531ms","start":"2026-07-02T05:12:38.594022Z","end":"2026-07-02T05:12:38.705617Z","steps":["trace[238911189] 'agreement among raft nodes before linearized reading' (duration: 111.062618ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:38.70464Z","caller":"traceutil/trace.go:171","msg":"trace[561238554] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"113.710504ms","start":"2026-07-02T05:12:38.590885Z","end":"2026-07-02T05:12:38.704595Z","steps":["trace[561238554] 'process raft request' (duration: 113.319474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:40.956818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.974369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:12:40.956927Z","caller":"traceutil/trace.go:171","msg":"trace[1630864912] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1187; }","duration":"203.165064ms","start":"2026-07-02T05:12:40.753735Z","end":"2026-07-02T05:12:40.9569Z","steps":["trace[1630864912] 'count revisions from in-memory index tree' (duration: 202.868777ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:41.225385Z","caller":"traceutil/trace.go:171","msg":"trace[1120908105] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1238; }","duration":"131.047857ms","start":"2026-07-02T05:12:41.094316Z","end":"2026-07-02T05:12:41.225364Z","steps":["trace[1120908105] 'read index received' (duration: 130.829491ms)","trace[1120908105] 'applied index is now lower than readState.Index' (duration: 217.636µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:12:41.225552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.2013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:12:41.225514Z","caller":"traceutil/trace.go:171","msg":"trace[763736459] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"452.751996ms","start":"2026-07-02T05:12:40.772702Z","end":"2026-07-02T05:12:41.225454Z","steps":["trace[763736459] 'process raft request' (duration: 452.52146ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:41.225587Z","caller":"traceutil/trace.go:171","msg":"trace[239886403] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1188; }","duration":"131.329034ms","start":"2026-07-02T05:12:41.094248Z","end":"2026-07-02T05:12:41.225577Z","steps":["trace[239886403] 'agreement among raft nodes before linearized reading' (duration: 131.238262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:41.225752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:40.772663Z","time spent":"452.945761ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:12:44.207995Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514556411341375,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:12:44.481828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.28069ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:12:44.481973Z","caller":"traceutil/trace.go:171","msg":"trace[1472956189] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1193; }","duration":"224.437744ms","start":"2026-07-02T05:12:44.257507Z","end":"2026-07-02T05:12:44.481945Z","steps":["trace[1472956189] 'range keys from in-memory index tree' (duration: 224.26484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:44.481878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.260566486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:12:44.482152Z","caller":"traceutil/trace.go:171","msg":"trace[1026150840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"2.260943826s","start":"2026-07-02T05:12:42.2212Z","end":"2026-07-02T05:12:44.482144Z","steps":["trace[1026150840] 'range keys from in-memory index tree' (duration: 2.260444104s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:44.482186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:42.221174Z","time spent":"2.260999358s","remote":"127.0.0.1:52362","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-07-02T05:12:44.482321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.917542067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:12:44.482422Z","caller":"traceutil/trace.go:171","msg":"trace[1253060359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1193; }","duration":"1.917719032s","start":"2026-07-02T05:12:42.564682Z","end":"2026-07-02T05:12:44.482401Z","steps":["trace[1253060359] 'range keys from in-memory index tree' (duration: 1.917260979s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:44.482478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:42.564658Z","time spent":"1.917811593s","remote":"127.0.0.1:52654","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":"info","ts":"2026-07-02T05:12:44.501375Z","caller":"traceutil/trace.go:171","msg":"trace[1790867612] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"794.237937ms","start":"2026-07-02T05:12:43.707107Z","end":"2026-07-02T05:12:44.501345Z","steps":["trace[1790867612] 'read index received' (duration: 794.155625ms)","trace[1790867612] 'applied index is now lower than readState.Index' (duration: 80.972µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:12:44.501462Z","caller":"traceutil/trace.go:171","msg":"trace[1959681458] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"957.2297ms","start":"2026-07-02T05:12:43.544215Z","end":"2026-07-02T05:12:44.501444Z","steps":["trace[1959681458] 'process raft request' (duration: 956.976754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:44.501574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:43.544184Z","time spent":"957.321202ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:12:44.501635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.517755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-02T05:12:44.501686Z","caller":"traceutil/trace.go:171","msg":"trace[1686016243] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:1194; }","duration":"794.623037ms","start":"2026-07-02T05:12:43.707047Z","end":"2026-07-02T05:12:44.50167Z","steps":["trace[1686016243] 'agreement among raft nodes before linearized reading' (duration: 794.430562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:44.50175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:43.707014Z","time spent":"794.72536ms","remote":"127.0.0.1:52394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"warn","ts":"2026-07-02T05:12:44.502917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.928226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T05:12:44.50297Z","caller":"traceutil/trace.go:171","msg":"trace[666247696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1195; }","duration":"504.999768ms","start":"2026-07-02T05:12:43.997956Z","end":"2026-07-02T05:12:44.502955Z","steps":["trace[666247696] 'agreement among raft nodes before linearized reading' (duration: 504.859555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:44.502945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.756226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:12:44.502946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.817046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-02T05:12:44.503005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:43.997946Z","time spent":"505.051129ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T05:12:44.502993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.496039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-02T05:12:44.503036Z","caller":"traceutil/trace.go:171","msg":"trace[994008502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"525.96478ms","start":"2026-07-02T05:12:43.977053Z","end":"2026-07-02T05:12:44.503018Z","steps":["trace[994008502] 'agreement among raft nodes before linearized reading' (duration: 525.710893ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:44.50304Z","caller":"traceutil/trace.go:171","msg":"trace[1205092274] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1195; }","duration":"178.894819ms","start":"2026-07-02T05:12:44.324111Z","end":"2026-07-02T05:12:44.503006Z","steps":["trace[1205092274] 'agreement among raft nodes before linearized reading' (duration: 178.733435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:44.503074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:43.977016Z","time spent":"526.050692ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T05:12:44.503073Z","caller":"traceutil/trace.go:171","msg":"trace[380826975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1195; }","duration":"521.610502ms","start":"2026-07-02T05:12:43.981444Z","end":"2026-07-02T05:12:44.503055Z","steps":["trace[380826975] 'agreement among raft nodes before linearized reading' (duration: 521.380926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:44.503146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:43.981434Z","time spent":"521.702174ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:12:44.503247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.859064ms","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-07-02T05:12:44.503281Z","caller":"traceutil/trace.go:171","msg":"trace[368650634] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1195; }","duration":"502.911456ms","start":"2026-07-02T05:12:44.000359Z","end":"2026-07-02T05:12:44.503271Z","steps":["trace[368650634] 'agreement among raft nodes before linearized reading' (duration: 502.811883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:44.50329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.490766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:12:44.503316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:44.00035Z","time spent":"502.957017ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T05:12:44.503336Z","caller":"traceutil/trace.go:171","msg":"trace[1953351163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"281.552478ms","start":"2026-07-02T05:12:44.221772Z","end":"2026-07-02T05:12:44.503324Z","steps":["trace[1953351163] 'agreement among raft nodes before linearized reading' (duration: 281.477136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:44.503575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.98577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:12:44.503617Z","caller":"traceutil/trace.go:171","msg":"trace[1253585798] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1195; }","duration":"341.114893ms","start":"2026-07-02T05:12:44.162491Z","end":"2026-07-02T05:12:44.503606Z","steps":["trace[1253585798] 'agreement among raft nodes before linearized reading' (duration: 340.322794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:44.503659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:12:44.162432Z","time spent":"341.216675ms","remote":"127.0.0.1:52686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:13:00.761004Z","caller":"traceutil/trace.go:171","msg":"trace[178276296] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"133.499598ms","start":"2026-07-02T05:13:00.627474Z","end":"2026-07-02T05:13:00.760974Z","steps":["trace[178276296] 'process raft request' (duration: 87.341357ms)","trace[178276296] 'compare' (duration: 46.044518ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:04.956627Z","caller":"traceutil/trace.go:171","msg":"trace[1938976638] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"311.919515ms","start":"2026-07-02T05:13:04.644674Z","end":"2026-07-02T05:13:04.956593Z","steps":["trace[1938976638] 'read index received' (duration: 311.556296ms)","trace[1938976638] 'applied index is now lower than readState.Index' (duration: 361.629µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:04.957793Z","caller":"traceutil/trace.go:171","msg":"trace[104510638] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"317.217765ms","start":"2026-07-02T05:13:04.640558Z","end":"2026-07-02T05:13:04.957775Z","steps":["trace[104510638] 'process raft request' (duration: 315.78184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:04.957939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:04.64053Z","time spent":"317.312908ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:04.958607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.322301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:13:04.958657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.270866ms","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-07-02T05:13:04.958699Z","caller":"traceutil/trace.go:171","msg":"trace[666127051] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1290; }","duration":"129.488135ms","start":"2026-07-02T05:13:04.82919Z","end":"2026-07-02T05:13:04.958678Z","steps":["trace[666127051] 'agreement among raft nodes before linearized reading' (duration: 129.29986ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:04.958711Z","caller":"traceutil/trace.go:171","msg":"trace[2009364868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1290; }","duration":"304.356948ms","start":"2026-07-02T05:13:04.654342Z","end":"2026-07-02T05:13:04.958699Z","steps":["trace[2009364868] 'agreement among raft nodes before linearized reading' (duration: 304.123422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:04.958709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.251694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T05:13:04.958792Z","caller":"traceutil/trace.go:171","msg":"trace[1399519938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1290; }","duration":"310.388907ms","start":"2026-07-02T05:13:04.648383Z","end":"2026-07-02T05:13:04.958772Z","steps":["trace[1399519938] 'agreement among raft nodes before linearized reading' (duration: 310.1111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:04.958806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.878969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-02T05:13:04.958752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:04.654326Z","time spent":"304.41694ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T05:13:04.958846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:04.648357Z","time spent":"310.4799ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T05:13:04.95886Z","caller":"traceutil/trace.go:171","msg":"trace[521155425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1290; }","duration":"310.950621ms","start":"2026-07-02T05:13:04.647894Z","end":"2026-07-02T05:13:04.958845Z","steps":["trace[521155425] 'agreement among raft nodes before linearized reading' (duration: 310.736095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:04.958803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.138435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-02T05:13:04.9589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:04.647888Z","time spent":"311.004432ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T05:13:04.958954Z","caller":"traceutil/trace.go:171","msg":"trace[376665193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1290; }","duration":"313.386091ms","start":"2026-07-02T05:13:04.645545Z","end":"2026-07-02T05:13:04.958931Z","steps":["trace[376665193] 'agreement among raft nodes before linearized reading' (duration: 312.905419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:04.959047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:04.645522Z","time spent":"313.507394ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T05:13:04.959193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.76838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-02T05:13:04.959236Z","caller":"traceutil/trace.go:171","msg":"trace[2041341643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1290; }","duration":"314.608891ms","start":"2026-07-02T05:13:04.644615Z","end":"2026-07-02T05:13:04.959224Z","steps":["trace[2041341643] 'agreement among raft nodes before linearized reading' (duration: 313.692808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:04.959302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:04.644588Z","time spent":"314.674783ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:13:05.478997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.873849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:13:05.479141Z","caller":"traceutil/trace.go:171","msg":"trace[1442680156] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"515.625621ms","start":"2026-07-02T05:13:04.963493Z","end":"2026-07-02T05:13:05.479118Z","steps":["trace[1442680156] 'process raft request' (duration: 202.539577ms)","trace[1442680156] 'compare' (duration: 312.446279ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:05.479205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:04.96346Z","time spent":"515.716103ms","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:13:05.561692Z","caller":"traceutil/trace.go:171","msg":"trace[560343457] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"597.45857ms","start":"2026-07-02T05:13:04.964197Z","end":"2026-07-02T05:13:05.561655Z","steps":["trace[560343457] 'process raft request' (duration: 597.06508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:05.561874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:04.964149Z","time spent":"597.623124ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:13:05.562628Z","caller":"traceutil/trace.go:171","msg":"trace[235959288] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"598.401733ms","start":"2026-07-02T05:13:04.964204Z","end":"2026-07-02T05:13:05.562606Z","steps":["trace[235959288] 'process raft request' (duration: 597.290566ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:05.562818Z","caller":"traceutil/trace.go:171","msg":"trace[1478497847] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"598.477956ms","start":"2026-07-02T05:13:04.964303Z","end":"2026-07-02T05:13:05.562781Z","steps":["trace[1478497847] 'process raft request' (duration: 598.122097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:05.563877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:04.964146Z","time spent":"599.669584ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:05.564066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:04.964272Z","time spent":"599.661714ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:13:05.56474Z","caller":"traceutil/trace.go:171","msg":"trace[868450660] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"598.884056ms","start":"2026-07-02T05:13:04.965836Z","end":"2026-07-02T05:13:05.56472Z","steps":["trace[868450660] 'process raft request' (duration: 596.738103ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:05.564899Z","caller":"traceutil/trace.go:171","msg":"trace[1059765042] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1346; }","duration":"495.653609ms","start":"2026-07-02T05:13:05.069226Z","end":"2026-07-02T05:13:05.564879Z","steps":["trace[1059765042] 'read index received' (duration: 96.897941ms)","trace[1059765042] 'applied index is now lower than readState.Index' (duration: 398.754718ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:05.565347Z","caller":"traceutil/trace.go:171","msg":"trace[1220882858] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"599.288456ms","start":"2026-07-02T05:13:04.966049Z","end":"2026-07-02T05:13:05.565337Z","steps":["trace[1220882858] 'process raft request' (duration: 598.609429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:05.565419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:04.966029Z","time spent":"599.354177ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:05.565547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.326485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:05.56558Z","caller":"traceutil/trace.go:171","msg":"trace[768071523] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1296; }","duration":"496.406998ms","start":"2026-07-02T05:13:05.069164Z","end":"2026-07-02T05:13:05.565571Z","steps":["trace[768071523] 'agreement among raft nodes before linearized reading' (duration: 496.349976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:05.565602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:05.069143Z","time spent":"496.453268ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-02T05:13:05.565708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.844738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:05.565732Z","caller":"traceutil/trace.go:171","msg":"trace[392936202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1296; }","duration":"342.91041ms","start":"2026-07-02T05:13:05.222815Z","end":"2026-07-02T05:13:05.565725Z","steps":["trace[392936202] 'agreement among raft nodes before linearized reading' (duration: 342.87313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:05.565748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:05.222784Z","time spent":"342.959851ms","remote":"127.0.0.1:52362","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-07-02T05:13:05.56699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:04.965822Z","time spent":"599.0549ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:13:06.762515Z","caller":"traceutil/trace.go:171","msg":"trace[883848861] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"227.653307ms","start":"2026-07-02T05:13:06.534833Z","end":"2026-07-02T05:13:06.762486Z","steps":["trace[883848861] 'process raft request' (duration: 227.445872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:13.59643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.587202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:13.596532Z","caller":"traceutil/trace.go:171","msg":"trace[210695502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1352; }","duration":"372.756146ms","start":"2026-07-02T05:13:13.223752Z","end":"2026-07-02T05:13:13.596508Z","steps":["trace[210695502] 'range keys from in-memory index tree' (duration: 372.448459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:13.596584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:13.223705Z","time spent":"372.870209ms","remote":"127.0.0.1:52362","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-07-02T05:13:13.596594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.113698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:13.596702Z","caller":"traceutil/trace.go:171","msg":"trace[994995964] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1352; }","duration":"346.290323ms","start":"2026-07-02T05:13:13.250386Z","end":"2026-07-02T05:13:13.596676Z","steps":["trace[994995964] 'count revisions from in-memory index tree' (duration: 345.996276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:13.596765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:13.250372Z","time spent":"346.380525ms","remote":"127.0.0.1:52516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:13:13.723868Z","caller":"traceutil/trace.go:171","msg":"trace[289306821] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"120.79116ms","start":"2026-07-02T05:13:13.603046Z","end":"2026-07-02T05:13:13.723837Z","steps":["trace[289306821] 'read index received' (duration: 120.618486ms)","trace[289306821] 'applied index is now lower than readState.Index' (duration: 171.034µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:13.724261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.18702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-02T05:13:13.72442Z","caller":"traceutil/trace.go:171","msg":"trace[2003721285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1353; }","duration":"121.377165ms","start":"2026-07-02T05:13:13.603021Z","end":"2026-07-02T05:13:13.724398Z","steps":["trace[2003721285] 'agreement among raft nodes before linearized reading' (duration: 121.067637ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:13.724329Z","caller":"traceutil/trace.go:171","msg":"trace[1610705708] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"122.490712ms","start":"2026-07-02T05:13:13.60181Z","end":"2026-07-02T05:13:13.724301Z","steps":["trace[1610705708] 'process raft request' (duration: 121.814635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:14.955176Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.150382009s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-02T05:13:15.176386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.635523ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:15.176529Z","caller":"traceutil/trace.go:171","msg":"trace[970070143] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1353; }","duration":"918.794307ms","start":"2026-07-02T05:13:14.257713Z","end":"2026-07-02T05:13:15.176507Z","steps":["trace[970070143] 'range keys from in-memory index tree' (duration: 918.614373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:15.177496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372648433s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:13:15.177726Z","caller":"traceutil/trace.go:171","msg":"trace[1243518859] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"1.448871283s","start":"2026-07-02T05:13:13.728839Z","end":"2026-07-02T05:13:15.17771Z","steps":["trace[1243518859] 'process raft request' (duration: 1.448842392s)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:15.17777Z","caller":"traceutil/trace.go:171","msg":"trace[1886202086] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"1.574105693s","start":"2026-07-02T05:13:13.603634Z","end":"2026-07-02T05:13:15.17774Z","steps":["trace[1886202086] 'process raft request' (duration: 201.126912ms)","trace[1886202086] 'compare' (duration: 1.371065463s)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:15.177858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:13.728818Z","time spent":"1.448984806s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:15.177914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:13.603616Z","time spent":"1.574211036s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:13:15.178092Z","caller":"traceutil/trace.go:171","msg":"trace[1933436223] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"1.571720184s","start":"2026-07-02T05:13:13.606325Z","end":"2026-07-02T05:13:15.178045Z","steps":["trace[1933436223] 'process raft request' (duration: 1.571291204s)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:15.178193Z","caller":"traceutil/trace.go:171","msg":"trace[1579511384] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1410; }","duration":"1.453858056s","start":"2026-07-02T05:13:13.724307Z","end":"2026-07-02T05:13:15.178165Z","steps":["trace[1579511384] 'read index received' (duration: 80.486675ms)","trace[1579511384] 'applied index is now lower than readState.Index' (duration: 1.373366781s)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:15.178302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:13.606317Z","time spent":"1.571873898s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:13:15.178575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.572466423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T05:13:15.178634Z","caller":"traceutil/trace.go:171","msg":"trace[533558321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1356; }","duration":"1.572542554s","start":"2026-07-02T05:13:13.606078Z","end":"2026-07-02T05:13:15.178621Z","steps":["trace[533558321] 'agreement among raft nodes before linearized reading' (duration: 1.57237442s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:15.178406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470253541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-02T05:13:15.178676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:13.606071Z","time spent":"1.572596976s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T05:13:15.178689Z","caller":"traceutil/trace.go:171","msg":"trace[1005817552] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:1356; }","duration":"1.470581689s","start":"2026-07-02T05:13:13.708089Z","end":"2026-07-02T05:13:15.17867Z","steps":["trace[1005817552] 'agreement among raft nodes before linearized reading' (duration: 1.470076136s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:15.178463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.571748535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T05:13:15.178798Z","caller":"traceutil/trace.go:171","msg":"trace[49985392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1356; }","duration":"1.572093733s","start":"2026-07-02T05:13:13.606692Z","end":"2026-07-02T05:13:15.178786Z","steps":["trace[49985392] 'agreement among raft nodes before linearized reading' (duration: 1.571662913s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:15.178851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:13.606686Z","time spent":"1.572155465s","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T05:13:15.178751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:13.708046Z","time spent":"1.470673361s","remote":"127.0.0.1:52394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"warn","ts":"2026-07-02T05:13:15.221091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16012564s","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-07-02T05:13:15.221068Z","caller":"traceutil/trace.go:171","msg":"trace[286787142] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"135.925563ms","start":"2026-07-02T05:13:15.085101Z","end":"2026-07-02T05:13:15.221026Z","steps":["trace[286787142] 'process raft request' (duration: 135.732539ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:15.221199Z","caller":"traceutil/trace.go:171","msg":"trace[1310039876] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1357; }","duration":"1.160255653s","start":"2026-07-02T05:13:14.060921Z","end":"2026-07-02T05:13:15.221177Z","steps":["trace[1310039876] 'agreement among raft nodes before linearized reading' (duration: 1.160064208s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:15.221255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.346912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T05:13:15.221329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:14.060887Z","time spent":"1.160402017s","remote":"127.0.0.1:57440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:13:15.221389Z","caller":"traceutil/trace.go:171","msg":"trace[369842600] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1357; }","duration":"628.631059ms","start":"2026-07-02T05:13:14.592737Z","end":"2026-07-02T05:13:15.221368Z","steps":["trace[369842600] 'agreement among raft nodes before linearized reading' (duration: 628.340892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:15.22152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:14.592709Z","time spent":"628.779933ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:13:15.221607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.741871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-02T05:13:15.222627Z","caller":"traceutil/trace.go:171","msg":"trace[579381756] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1357; }","duration":"738.855728ms","start":"2026-07-02T05:13:14.483734Z","end":"2026-07-02T05:13:15.22259Z","steps":["trace[579381756] 'agreement among raft nodes before linearized reading' (duration: 737.368581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:15.222738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:14.483707Z","time spent":"738.993562ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-02T05:15:01.675382Z","caller":"traceutil/trace.go:171","msg":"trace[634434864] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"127.047456ms","start":"2026-07-02T05:15:01.548309Z","end":"2026-07-02T05:15:01.675356Z","steps":["trace[634434864] 'process raft request' (duration: 126.870991ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:15:34.584881Z","caller":"traceutil/trace.go:171","msg":"trace[139402617] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"107.72737ms","start":"2026-07-02T05:15:34.477112Z","end":"2026-07-02T05:15:34.58484Z","steps":["trace[139402617] 'process raft request' (duration: 107.654418ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:15:34.585263Z","caller":"traceutil/trace.go:171","msg":"trace[305795177] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"108.558153ms","start":"2026-07-02T05:15:34.476679Z","end":"2026-07-02T05:15:34.585237Z","steps":["trace[305795177] 'process raft request' (duration: 108.013958ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:15:34.585486Z","caller":"traceutil/trace.go:171","msg":"trace[439017865] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"109.006635ms","start":"2026-07-02T05:15:34.476456Z","end":"2026-07-02T05:15:34.585462Z","steps":["trace[439017865] 'process raft request' (duration: 94.854114ms)","trace[439017865] 'compare' (duration: 13.224946ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:15:53.87397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.24656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:15:53.874237Z","caller":"traceutil/trace.go:171","msg":"trace[833721231] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"129.915719ms","start":"2026-07-02T05:15:53.744292Z","end":"2026-07-02T05:15:53.874207Z","steps":["trace[833721231] 'process raft request' (duration: 26.308309ms)","trace[833721231] 'compare' (duration: 103.001454ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:15:59.043798Z","caller":"traceutil/trace.go:171","msg":"trace[720476713] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"137.659298ms","start":"2026-07-02T05:15:58.906102Z","end":"2026-07-02T05:15:59.043761Z","steps":["trace[720476713] 'process raft request' (duration: 39.36398ms)","trace[720476713] 'compare' (duration: 98.167954ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:16:43.671716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514556411344065,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T05:16:43.679465Z","caller":"traceutil/trace.go:171","msg":"trace[1132645230] linearizableReadLoop","detail":"{readStateIndex:2408; appliedIndex:2407; }","duration":"507.946363ms","start":"2026-07-02T05:16:43.171455Z","end":"2026-07-02T05:16:43.679401Z","steps":["trace[1132645230] 'read index received' (duration: 507.761387ms)","trace[1132645230] 'applied index is now lower than readState.Index' (duration: 183.796µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:16:43.679498Z","caller":"traceutil/trace.go:171","msg":"trace[913207530] transaction","detail":"{read_only:false; response_revision:2309; number_of_response:1; }","duration":"552.835937ms","start":"2026-07-02T05:16:43.126635Z","end":"2026-07-02T05:16:43.679471Z","steps":["trace[913207530] 'process raft request' (duration: 552.596911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:16:43.679662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.181579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:16:43.67967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:16:43.126617Z","time spent":"552.95162ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:16:43.679708Z","caller":"traceutil/trace.go:171","msg":"trace[2055668045] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2309; }","duration":"508.330493ms","start":"2026-07-02T05:16:43.171366Z","end":"2026-07-02T05:16:43.679696Z","steps":["trace[2055668045] 'agreement among raft nodes before linearized reading' (duration: 508.22292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:16:43.679746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:16:43.171342Z","time spent":"508.394295ms","remote":"127.0.0.1:52516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:16:43.679841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.343252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:16:43.679928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.918922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:16:43.679941Z","caller":"traceutil/trace.go:171","msg":"trace[2033752434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2309; }","duration":"457.480345ms","start":"2026-07-02T05:16:43.222437Z","end":"2026-07-02T05:16:43.679917Z","steps":["trace[2033752434] 'agreement among raft nodes before linearized reading' (duration: 457.31703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:16:43.679932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.957477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-02T05:16:43.679989Z","caller":"traceutil/trace.go:171","msg":"trace[1208844090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2309; }","duration":"504.020335ms","start":"2026-07-02T05:16:43.17595Z","end":"2026-07-02T05:16:43.679971Z","steps":["trace[1208844090] 'agreement among raft nodes before linearized reading' (duration: 503.781899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:16:43.679991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:16:43.222425Z","time spent":"457.557097ms","remote":"127.0.0.1:52362","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-07-02T05:16:43.680027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:16:43.175934Z","time spent":"504.085716ms","remote":"127.0.0.1:52654","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":"info","ts":"2026-07-02T05:16:43.680032Z","caller":"traceutil/trace.go:171","msg":"trace[1927294624] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2309; }","duration":"399.06578ms","start":"2026-07-02T05:16:43.280944Z","end":"2026-07-02T05:16:43.680009Z","steps":["trace[1927294624] 'agreement among raft nodes before linearized reading' (duration: 398.800313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:16:43.680077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:16:43.280894Z","time spent":"399.173304ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-02T05:16:44.558673Z","caller":"traceutil/trace.go:171","msg":"trace[1046330698] transaction","detail":"{read_only:false; response_revision:2314; number_of_response:1; }","duration":"183.898949ms","start":"2026-07-02T05:16:44.374751Z","end":"2026-07-02T05:16:44.55865Z","steps":["trace[1046330698] 'process raft request' (duration: 183.743195ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:16:44.560851Z","caller":"traceutil/trace.go:171","msg":"trace[519549553] linearizableReadLoop","detail":"{readStateIndex:2415; appliedIndex:2413; }","duration":"104.3998ms","start":"2026-07-02T05:16:44.456425Z","end":"2026-07-02T05:16:44.560825Z","steps":["trace[519549553] 'read index received' (duration: 102.189349ms)","trace[519549553] 'applied index is now lower than readState.Index' (duration: 2.20925ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:16:44.560877Z","caller":"traceutil/trace.go:171","msg":"trace[1689800470] transaction","detail":"{read_only:false; response_revision:2315; number_of_response:1; }","duration":"125.71254ms","start":"2026-07-02T05:16:44.435143Z","end":"2026-07-02T05:16:44.560856Z","steps":["trace[1689800470] 'process raft request' (duration: 125.542696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:16:44.56104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.597835ms","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-07-02T05:16:44.561123Z","caller":"traceutil/trace.go:171","msg":"trace[780807214] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2315; }","duration":"104.732098ms","start":"2026-07-02T05:16:44.456371Z","end":"2026-07-02T05:16:44.561104Z","steps":["trace[780807214] 'agreement among raft nodes before linearized reading' (duration: 104.577714ms)"],"step_count":1}