{"level":"warn","ts":"2026-03-31T22:19:31.616446Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T22:19:31.616529Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.73:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.73:2380","--initial-cluster=instance=https://199.19.213.73:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.73:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.73: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-03-31T22:19:31.616591Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T22:19:31.616603Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-03-31T22:19:31.616636Z","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-03-31T22:19:31.617235Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"]} {"level":"info","ts":"2026-03-31T22:19:31.617399Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.73: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-03-31T22:19:31.621762Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.163163ms"} {"level":"info","ts":"2026-03-31T22:19:31.631855Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"34a8f480fa9ab6cb","cluster-id":"4820536600e0938e"} {"level":"info","ts":"2026-03-31T22:19:31.631964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T22:19:31.632052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 0"} {"level":"info","ts":"2026-03-31T22:19:31.632074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 34a8f480fa9ab6cb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T22:19:31.63216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 1"} {"level":"info","ts":"2026-03-31T22:19:31.632292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"warn","ts":"2026-03-31T22:19:31.639538Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T22:19:31.642764Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T22:19:31.644036Z","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-03-31T22:19:31.646285Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"34a8f480fa9ab6cb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T22:19:31.646363Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"34a8f480fa9ab6cb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T22:19:31.646463Z","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-03-31T22:19:31.646513Z","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-03-31T22:19:31.646523Z","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-03-31T22:19:31.648047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"info","ts":"2026-03-31T22:19:31.64858Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","added-peer-id":"34a8f480fa9ab6cb","added-peer-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-03-31T22:19:31.649982Z","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-03-31T22:19:31.650177Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"34a8f480fa9ab6cb","initial-advertise-peer-urls":["https://199.19.213.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T22:19:31.650196Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-03-31T22:19:31.650275Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-03-31T22:19:31.650219Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T22:19:32.032721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T22:19:32.032831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T22:19:32.032876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgPreVoteResp from 34a8f480fa9ab6cb at term 1"} {"level":"info","ts":"2026-03-31T22:19:32.032933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became candidate at term 2"} {"level":"info","ts":"2026-03-31T22:19:32.032964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgVoteResp from 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-03-31T22:19:32.033004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became leader at term 2"} {"level":"info","ts":"2026-03-31T22:19:32.03304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 34a8f480fa9ab6cb elected leader 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-03-31T22:19:32.033855Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T22:19:32.034686Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T22:19:32.034689Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"34a8f480fa9ab6cb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.73:2379]}","request-path":"/0/members/34a8f480fa9ab6cb/attributes","cluster-id":"4820536600e0938e","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T22:19:32.03472Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T22:19:32.035182Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T22:19:32.035264Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T22:19:32.038289Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T22:19:32.038956Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.73:2379"} {"level":"info","ts":"2026-03-31T22:19:32.044757Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T22:19:32.045297Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T22:19:32.045412Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-31T22:19:37.084151Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794459064775232,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T22:19:37.097415Z","caller":"traceutil/trace.go:171","msg":"trace[1976588768] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"552.587907ms","start":"2026-03-31T22:19:36.544804Z","end":"2026-03-31T22:19:37.097392Z","steps":["trace[1976588768] 'process raft request' (duration: 552.460883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:37.098093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:36.544773Z","time spent":"552.731922ms","remote":"127.0.0.1:56206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":205,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:19:38.815304Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.717657627s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T22:19:38.815908Z","caller":"traceutil/trace.go:171","msg":"trace[1548390128] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:245; }","duration":"2.232528336s","start":"2026-03-31T22:19:36.583354Z","end":"2026-03-31T22:19:38.815883Z","steps":["trace[1548390128] 'read index received' (duration: 513.983301ms)","trace[1548390128] 'applied index is now lower than readState.Index' (duration: 1.718543825s)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:19:38.815906Z","caller":"traceutil/trace.go:171","msg":"trace[1098711598] transaction","detail":"{read_only:false; number_of_response:0; response_revision:241; }","duration":"2.035676696s","start":"2026-03-31T22:19:36.780195Z","end":"2026-03-31T22:19:38.815871Z","steps":["trace[1098711598] 'process raft request' (duration: 2.035464889s)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:38.816098Z","caller":"traceutil/trace.go:171","msg":"trace[478224683] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"2.015999086s","start":"2026-03-31T22:19:36.800092Z","end":"2026-03-31T22:19:38.816091Z","steps":["trace[478224683] 'process raft request' (duration: 2.015616324s)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:38.815933Z","caller":"traceutil/trace.go:171","msg":"trace[1833617059] transaction","detail":"{read_only:false; number_of_response:0; response_revision:243; }","duration":"2.014237229s","start":"2026-03-31T22:19:36.801669Z","end":"2026-03-31T22:19:38.815906Z","steps":["trace[1833617059] 'process raft request' (duration: 2.014136956s)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:38.816191Z","caller":"traceutil/trace.go:171","msg":"trace[1769757668] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"2.01549348s","start":"2026-03-31T22:19:36.800679Z","end":"2026-03-31T22:19:38.816173Z","steps":["trace[1769757668] 'process raft request' (duration: 2.015095907s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:38.816252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:36.800052Z","time spent":"2.016079038s","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:19:38.81631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:36.800664Z","time spent":"2.015593163s","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:19:38.816308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.603451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T22:19:38.816037Z","caller":"traceutil/trace.go:171","msg":"trace[22177566] transaction","detail":"{read_only:false; number_of_response:0; response_revision:243; }","duration":"2.013709063s","start":"2026-03-31T22:19:36.802314Z","end":"2026-03-31T22:19:38.816023Z","steps":["trace[22177566] 'process raft request' (duration: 2.013517167s)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:38.816392Z","caller":"traceutil/trace.go:171","msg":"trace[1517167446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:243; }","duration":"529.726905ms","start":"2026-03-31T22:19:38.286645Z","end":"2026-03-31T22:19:38.816372Z","steps":["trace[1517167446] 'agreement among raft nodes before linearized reading' (duration: 529.56114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:38.816433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:38.286604Z","time spent":"529.820998ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T22:19:38.81644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:36.802303Z","time spent":"2.014077764s","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:19:38.816514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.727631116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:19:38.816548Z","caller":"traceutil/trace.go:171","msg":"trace[1749748198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:243; }","duration":"1.727698598s","start":"2026-03-31T22:19:37.08884Z","end":"2026-03-31T22:19:38.816538Z","steps":["trace[1749748198] 'agreement among raft nodes before linearized reading' (duration: 1.727656467s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:38.816574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:37.088815Z","time spent":"1.72775401s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T22:19:38.81665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.714065892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:19:38.816065Z","caller":"traceutil/trace.go:171","msg":"trace[1890794789] transaction","detail":"{read_only:false; number_of_response:0; response_revision:243; }","duration":"2.013268758s","start":"2026-03-31T22:19:36.80279Z","end":"2026-03-31T22:19:38.816058Z","steps":["trace[1890794789] 'process raft request' (duration: 2.013067431s)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:38.816678Z","caller":"traceutil/trace.go:171","msg":"trace[2084869548] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:243; }","duration":"1.714129774s","start":"2026-03-31T22:19:37.102541Z","end":"2026-03-31T22:19:38.816671Z","steps":["trace[2084869548] 'agreement among raft nodes before linearized reading' (duration: 1.714090682s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:38.816709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:37.102515Z","time spent":"1.714185796s","remote":"127.0.0.1:56206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" "} {"level":"warn","ts":"2026-03-31T22:19:38.816766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.233410244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:19:38.816776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:36.802774Z","time spent":"2.013971221s","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:19:38.816878Z","caller":"traceutil/trace.go:171","msg":"trace[1190559713] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:243; }","duration":"2.233502688s","start":"2026-03-31T22:19:36.583296Z","end":"2026-03-31T22:19:38.816799Z","steps":["trace[1190559713] 'agreement among raft nodes before linearized reading' (duration: 2.233437076s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:38.816333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:36.78017Z","time spent":"2.035960134s","remote":"127.0.0.1:56176","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:19:38.817014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:36.801641Z","time spent":"2.014643052s","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:19:38.816921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:36.583275Z","time spent":"2.233634982s","remote":"127.0.0.1:56446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"warn","ts":"2026-03-31T22:19:38.817178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.138054542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-03-31T22:19:38.817245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.136217544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-31T22:19:38.817241Z","caller":"traceutil/trace.go:171","msg":"trace[1573587054] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:243; }","duration":"2.138167816s","start":"2026-03-31T22:19:36.679056Z","end":"2026-03-31T22:19:38.817223Z","steps":["trace[1573587054] 'agreement among raft nodes before linearized reading' (duration: 2.137057881s)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:38.817289Z","caller":"traceutil/trace.go:171","msg":"trace[1124921901] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:243; }","duration":"2.136290906s","start":"2026-03-31T22:19:36.680989Z","end":"2026-03-31T22:19:38.81728Z","steps":["trace[1124921901] 'agreement among raft nodes before linearized reading' (duration: 2.136221334s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:38.816446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201725453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:19:38.81716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363675426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T22:19:38.817477Z","caller":"traceutil/trace.go:171","msg":"trace[843410872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:243; }","duration":"1.364138492s","start":"2026-03-31T22:19:37.453312Z","end":"2026-03-31T22:19:38.817451Z","steps":["trace[843410872] 'agreement among raft nodes before linearized reading' (duration: 1.362882541s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:38.839546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:36.680971Z","time spent":"2.158568859s","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-31T22:19:38.839576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:37.453281Z","time spent":"1.38627789s","remote":"127.0.0.1:56278","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-03-31T22:19:38.817332Z","caller":"traceutil/trace.go:171","msg":"trace[328706361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:243; }","duration":"1.202639702s","start":"2026-03-31T22:19:37.614677Z","end":"2026-03-31T22:19:38.817317Z","steps":["trace[328706361] 'agreement among raft nodes before linearized reading' (duration: 1.201744104s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:38.839643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:36.679038Z","time spent":"2.160595783s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-03-31T22:19:38.839635Z","caller":"traceutil/trace.go:171","msg":"trace[1655193866] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"2.133876429s","start":"2026-03-31T22:19:36.681892Z","end":"2026-03-31T22:19:38.815769Z","steps":["trace[1655193866] 'process raft request' (duration: 2.133622241s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:38.839673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:37.61466Z","time spent":"1.224999098s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T22:19:38.839714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:36.68188Z","time spent":"2.157801314s","remote":"127.0.0.1:56056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:19:39.502513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.916396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T22:19:39.50282Z","caller":"traceutil/trace.go:171","msg":"trace[1206448545] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"658.466286ms","start":"2026-03-31T22:19:38.844283Z","end":"2026-03-31T22:19:39.502749Z","steps":["trace[1206448545] 'process raft request' (duration: 204.963591ms)","trace[1206448545] 'compare' (duration: 452.73143ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:19:39.502857Z","caller":"traceutil/trace.go:171","msg":"trace[2010962371] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"658.409594ms","start":"2026-03-31T22:19:38.844417Z","end":"2026-03-31T22:19:39.502826Z","steps":["trace[2010962371] 'process raft request' (duration: 658.219258ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:39.502946Z","caller":"traceutil/trace.go:171","msg":"trace[846520106] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"658.428435ms","start":"2026-03-31T22:19:38.844494Z","end":"2026-03-31T22:19:39.502922Z","steps":["trace[846520106] 'process raft request' (duration: 658.303621ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:39.503019Z","caller":"traceutil/trace.go:171","msg":"trace[1229829414] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:253; }","duration":"658.520438ms","start":"2026-03-31T22:19:38.844457Z","end":"2026-03-31T22:19:39.502978Z","steps":["trace[1229829414] 'read index received' (duration: 204.884178ms)","trace[1229829414] 'applied index is now lower than readState.Index' (duration: 453.635309ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:19:39.503016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:38.844272Z","time spent":"658.656342ms","remote":"127.0.0.1:56056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:19:39.503045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:38.844488Z","time spent":"658.516117ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:19:39.503121Z","caller":"traceutil/trace.go:171","msg":"trace[1796356000] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"658.58702ms","start":"2026-03-31T22:19:38.844469Z","end":"2026-03-31T22:19:39.503056Z","steps":["trace[1796356000] 'process raft request' (duration: 658.295171ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:39.503157Z","caller":"traceutil/trace.go:171","msg":"trace[1083322202] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"652.924769ms","start":"2026-03-31T22:19:38.850221Z","end":"2026-03-31T22:19:39.503146Z","steps":["trace[1083322202] 'process raft request' (duration: 652.712372ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:39.503242Z","caller":"traceutil/trace.go:171","msg":"trace[2129867917] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"658.703614ms","start":"2026-03-31T22:19:38.844525Z","end":"2026-03-31T22:19:39.503229Z","steps":["trace[2129867917] 'process raft request' (duration: 658.319171ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:39.503347Z","caller":"traceutil/trace.go:171","msg":"trace[1928083299] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"658.517748ms","start":"2026-03-31T22:19:38.844813Z","end":"2026-03-31T22:19:39.503331Z","steps":["trace[1928083299] 'process raft request' (duration: 658.058493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:39.503398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:38.844808Z","time spent":"658.565079ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:19:39.503397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:38.844457Z","time spent":"658.713034ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:19:39.5034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.056075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-03-31T22:19:39.503461Z","caller":"traceutil/trace.go:171","msg":"trace[1180678880] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:250; }","duration":"659.136958ms","start":"2026-03-31T22:19:38.844313Z","end":"2026-03-31T22:19:39.50345Z","steps":["trace[1180678880] 'agreement among raft nodes before linearized reading' (duration: 659.056375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:39.503466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:38.844521Z","time spent":"658.751565ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:19:39.503514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:38.844295Z","time spent":"659.188759ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":3996,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-03-31T22:19:39.503317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.864238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-31T22:19:39.503546Z","caller":"traceutil/trace.go:171","msg":"trace[1735406892] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:250; }","duration":"659.105917ms","start":"2026-03-31T22:19:38.844433Z","end":"2026-03-31T22:19:39.503539Z","steps":["trace[1735406892] 'agreement among raft nodes before linearized reading' (duration: 658.834318ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:39.50354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:38.850212Z","time spent":"652.998581ms","remote":"127.0.0.1:56176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4201,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:19:39.503567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:38.844427Z","time spent":"659.134677ms","remote":"127.0.0.1:56090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-31T22:19:39.503587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.822736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:19:39.503622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.279842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-31T22:19:39.503642Z","caller":"traceutil/trace.go:171","msg":"trace[738050285] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:250; }","duration":"657.889197ms","start":"2026-03-31T22:19:38.845736Z","end":"2026-03-31T22:19:39.503625Z","steps":["trace[738050285] 'agreement among raft nodes before linearized reading' (duration: 657.788644ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:39.503671Z","caller":"traceutil/trace.go:171","msg":"trace[1806228114] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:250; }","duration":"659.323613ms","start":"2026-03-31T22:19:38.84433Z","end":"2026-03-31T22:19:39.503654Z","steps":["trace[1806228114] 'agreement among raft nodes before linearized reading' (duration: 659.23652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:39.503696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:38.845728Z","time spent":"657.955429ms","remote":"127.0.0.1:56080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"warn","ts":"2026-03-31T22:19:39.503407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:38.84441Z","time spent":"658.520277ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:19:39.50373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:38.844302Z","time spent":"659.415236ms","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-31T22:19:39.503806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.337308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:19:39.503858Z","caller":"traceutil/trace.go:171","msg":"trace[1195137892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:250; }","duration":"656.398699ms","start":"2026-03-31T22:19:38.847445Z","end":"2026-03-31T22:19:39.503844Z","steps":["trace[1195137892] 'agreement among raft nodes before linearized reading' (duration: 656.321157ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:39.504033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:19:38.847438Z","time spent":"656.579216ms","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T22:20:03.820212Z","caller":"traceutil/trace.go:171","msg":"trace[831138789] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"192.531362ms","start":"2026-03-31T22:20:03.627667Z","end":"2026-03-31T22:20:03.820199Z","steps":["trace[831138789] 'process raft request' (duration: 192.434879ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:03.820497Z","caller":"traceutil/trace.go:171","msg":"trace[778643325] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:490; }","duration":"183.482641ms","start":"2026-03-31T22:20:03.636981Z","end":"2026-03-31T22:20:03.820463Z","steps":["trace[778643325] 'read index received' (duration: 183.462501ms)","trace[778643325] 'applied index is now lower than readState.Index' (duration: 17.99µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:20:03.82062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.628226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T22:20:03.820649Z","caller":"traceutil/trace.go:171","msg":"trace[1895821967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"183.696938ms","start":"2026-03-31T22:20:03.636944Z","end":"2026-03-31T22:20:03.820641Z","steps":["trace[1895821967] 'agreement among raft nodes before linearized reading' (duration: 183.604825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.321506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794459064775702,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T22:20:04.338626Z","caller":"traceutil/trace.go:171","msg":"trace[59524415] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"538.051806ms","start":"2026-03-31T22:20:03.800529Z","end":"2026-03-31T22:20:04.338581Z","steps":["trace[59524415] 'process raft request' (duration: 537.008802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.338767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.80051Z","time spent":"538.18121ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:20:04.33902Z","caller":"traceutil/trace.go:171","msg":"trace[1827586327] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"538.450278ms","start":"2026-03-31T22:20:03.800555Z","end":"2026-03-31T22:20:04.339005Z","steps":["trace[1827586327] 'process raft request' (duration: 537.604461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.339093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.800529Z","time spent":"538.531871ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:20:04.339236Z","caller":"traceutil/trace.go:171","msg":"trace[1299001239] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"526.138533ms","start":"2026-03-31T22:20:03.813078Z","end":"2026-03-31T22:20:04.339216Z","steps":["trace[1299001239] 'process raft request' (duration: 525.704379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.339288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.81306Z","time spent":"526.204755ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:20:04.339367Z","caller":"traceutil/trace.go:171","msg":"trace[1219129596] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"533.306993ms","start":"2026-03-31T22:20:03.806051Z","end":"2026-03-31T22:20:04.339358Z","steps":["trace[1219129596] 'process raft request' (duration: 532.599561ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.339419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.806041Z","time spent":"533.355925ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:20:04.339503Z","caller":"traceutil/trace.go:171","msg":"trace[932301944] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"524.156019ms","start":"2026-03-31T22:20:03.815225Z","end":"2026-03-31T22:20:04.339381Z","steps":["trace[932301944] 'process raft request' (duration: 523.749676ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:04.339672Z","caller":"traceutil/trace.go:171","msg":"trace[2122451396] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"521.647119ms","start":"2026-03-31T22:20:03.818003Z","end":"2026-03-31T22:20:04.33965Z","steps":["trace[2122451396] 'process raft request' (duration: 521.016059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.339781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.815208Z","time spent":"524.431148ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:20:04.339804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.817996Z","time spent":"521.724361ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:20:04.340219Z","caller":"traceutil/trace.go:171","msg":"trace[1127773738] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"521.100652ms","start":"2026-03-31T22:20:03.819102Z","end":"2026-03-31T22:20:04.340203Z","steps":["trace[1127773738] 'process raft request' (duration: 520.010997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.340391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.819096Z","time spent":"521.248966ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:20:04.464203Z","caller":"traceutil/trace.go:171","msg":"trace[335480974] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"635.0551ms","start":"2026-03-31T22:20:03.82913Z","end":"2026-03-31T22:20:04.464185Z","steps":["trace[335480974] 'process raft request' (duration: 634.979477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.464308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.829116Z","time spent":"635.142873ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:20:04.464406Z","caller":"traceutil/trace.go:171","msg":"trace[999289775] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"638.123348ms","start":"2026-03-31T22:20:03.826276Z","end":"2026-03-31T22:20:04.4644Z","steps":["trace[999289775] 'process raft request' (duration: 637.741156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.464451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.826268Z","time spent":"638.156499ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:20:04.464593Z","caller":"traceutil/trace.go:171","msg":"trace[1927011664] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"636.697462ms","start":"2026-03-31T22:20:03.827889Z","end":"2026-03-31T22:20:04.464587Z","steps":["trace[1927011664] 'process raft request' (duration: 636.180946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.464627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.827879Z","time spent":"636.734724ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:20:04.464577Z","caller":"traceutil/trace.go:171","msg":"trace[2135108846] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"642.291283ms","start":"2026-03-31T22:20:03.822211Z","end":"2026-03-31T22:20:04.464502Z","steps":["trace[2135108846] 'process raft request' (duration: 639.08011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.464752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.822201Z","time spent":"642.485279ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:20:04.46532Z","caller":"traceutil/trace.go:171","msg":"trace[791884681] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"612.122173ms","start":"2026-03-31T22:20:03.85318Z","end":"2026-03-31T22:20:04.465302Z","steps":["trace[791884681] 'process raft request' (duration: 610.972446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.465488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.853166Z","time spent":"612.255228ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:20:04.465858Z","caller":"traceutil/trace.go:171","msg":"trace[89805468] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"487.853884ms","start":"2026-03-31T22:20:03.97799Z","end":"2026-03-31T22:20:04.465844Z","steps":["trace[89805468] 'process raft request' (duration: 487.315597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.465924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.977956Z","time spent":"487.939687ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:20:04.465927Z","caller":"traceutil/trace.go:171","msg":"trace[844631567] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"443.296503ms","start":"2026-03-31T22:20:04.022614Z","end":"2026-03-31T22:20:04.465911Z","steps":["trace[844631567] 'process raft request' (duration: 443.122477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:04.022589Z","time spent":"443.372646ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:20:04.465981Z","caller":"traceutil/trace.go:171","msg":"trace[433935457] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:490; }","duration":"645.32437ms","start":"2026-03-31T22:20:03.820529Z","end":"2026-03-31T22:20:04.465853Z","steps":["trace[433935457] 'read index received' (duration: 517.278659ms)","trace[433935457] 'applied index is now lower than readState.Index' (duration: 128.044301ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:20:04.466151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.293604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T22:20:04.466185Z","caller":"traceutil/trace.go:171","msg":"trace[736916980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:489; }","duration":"799.356476ms","start":"2026-03-31T22:20:03.666822Z","end":"2026-03-31T22:20:04.466178Z","steps":["trace[736916980] 'agreement among raft nodes before linearized reading' (duration: 799.312754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.466204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.666804Z","time spent":"799.397026ms","remote":"127.0.0.1:56278","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-03-31T22:20:04.466112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.863243ms","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-03-31T22:20:04.466311Z","caller":"traceutil/trace.go:171","msg":"trace[1021471836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:489; }","duration":"796.081011ms","start":"2026-03-31T22:20:03.670217Z","end":"2026-03-31T22:20:04.466298Z","steps":["trace[1021471836] 'agreement among raft nodes before linearized reading' (duration: 795.855033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.466381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.670201Z","time spent":"796.140742ms","remote":"127.0.0.1:56278","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":"info","ts":"2026-03-31T22:20:04.470272Z","caller":"traceutil/trace.go:171","msg":"trace[1189355350] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"122.870036ms","start":"2026-03-31T22:20:04.347381Z","end":"2026-03-31T22:20:04.470251Z","steps":["trace[1189355350] 'process raft request' (duration: 122.653079ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:04.470384Z","caller":"traceutil/trace.go:171","msg":"trace[1625886213] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"121.152621ms","start":"2026-03-31T22:20:04.349223Z","end":"2026-03-31T22:20:04.470376Z","steps":["trace[1625886213] 'process raft request' (duration: 120.937444ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:04.470452Z","caller":"traceutil/trace.go:171","msg":"trace[2075062691] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"123.01002ms","start":"2026-03-31T22:20:04.347436Z","end":"2026-03-31T22:20:04.470446Z","steps":["trace[2075062691] 'process raft request' (duration: 122.667059ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:04.470516Z","caller":"traceutil/trace.go:171","msg":"trace[443322491] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"123.060522ms","start":"2026-03-31T22:20:04.347451Z","end":"2026-03-31T22:20:04.470511Z","steps":["trace[443322491] 'process raft request' (duration: 122.67848ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:04.470696Z","caller":"traceutil/trace.go:171","msg":"trace[1278938171] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"121.390648ms","start":"2026-03-31T22:20:04.349299Z","end":"2026-03-31T22:20:04.47069Z","steps":["trace[1278938171] 'process raft request' (duration: 120.929613ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:04.470758Z","caller":"traceutil/trace.go:171","msg":"trace[1735454791] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"120.877772ms","start":"2026-03-31T22:20:04.349874Z","end":"2026-03-31T22:20:04.470752Z","steps":["trace[1735454791] 'process raft request' (duration: 120.804479ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:04.470805Z","caller":"traceutil/trace.go:171","msg":"trace[1706489157] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"121.390468ms","start":"2026-03-31T22:20:04.34941Z","end":"2026-03-31T22:20:04.470801Z","steps":["trace[1706489157] 'process raft request' (duration: 120.913393ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:04.47098Z","caller":"traceutil/trace.go:171","msg":"trace[1738954332] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"119.965862ms","start":"2026-03-31T22:20:04.350991Z","end":"2026-03-31T22:20:04.470957Z","steps":["trace[1738954332] 'process raft request' (duration: 119.733285ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.472531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.298883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:04.472606Z","caller":"traceutil/trace.go:171","msg":"trace[758495252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"623.407207ms","start":"2026-03-31T22:20:03.849183Z","end":"2026-03-31T22:20:04.47259Z","steps":["trace[758495252] 'agreement among raft nodes before linearized reading' (duration: 623.238981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:04.472647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:03.849166Z","time spent":"623.470568ms","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T22:20:04.472889Z","caller":"traceutil/trace.go:171","msg":"trace[523777768] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"119.031072ms","start":"2026-03-31T22:20:04.353835Z","end":"2026-03-31T22:20:04.472866Z","steps":["trace[523777768] 'process raft request' (duration: 118.349721ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:04.473047Z","caller":"traceutil/trace.go:171","msg":"trace[734723339] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"120.272802ms","start":"2026-03-31T22:20:04.352767Z","end":"2026-03-31T22:20:04.47304Z","steps":["trace[734723339] 'process raft request' (duration: 119.28564ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:04.473108Z","caller":"traceutil/trace.go:171","msg":"trace[1340677005] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"116.986106ms","start":"2026-03-31T22:20:04.356114Z","end":"2026-03-31T22:20:04.4731Z","steps":["trace[1340677005] 'process raft request' (duration: 116.230082ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:04.923055Z","caller":"traceutil/trace.go:171","msg":"trace[1744949155] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"234.550901ms","start":"2026-03-31T22:20:04.688471Z","end":"2026-03-31T22:20:04.923022Z","steps":["trace[1744949155] 'process raft request' (duration: 234.254841ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:04.93069Z","caller":"traceutil/trace.go:171","msg":"trace[584843047] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"235.313236ms","start":"2026-03-31T22:20:04.695346Z","end":"2026-03-31T22:20:04.930659Z","steps":["trace[584843047] 'process raft request' (duration: 235.019717ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:04.930965Z","caller":"traceutil/trace.go:171","msg":"trace[31451941] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"216.892614ms","start":"2026-03-31T22:20:04.714062Z","end":"2026-03-31T22:20:04.930954Z","steps":["trace[31451941] 'process raft request' (duration: 216.502201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:10.993722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794459064775864,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T22:20:11.098301Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.159483727s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-31T22:20:11.134759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274757698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T22:20:11.134857Z","caller":"traceutil/trace.go:171","msg":"trace[702522530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"1.274973065s","start":"2026-03-31T22:20:09.859863Z","end":"2026-03-31T22:20:11.134836Z","steps":["trace[702522530] 'range keys from in-memory index tree' (duration: 1.274568572s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:11.134904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:09.859838Z","time spent":"1.275055728s","remote":"127.0.0.1:56278","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-03-31T22:20:11.135132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2851042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:11.135209Z","caller":"traceutil/trace.go:171","msg":"trace[349390690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"1.285187363s","start":"2026-03-31T22:20:09.85Z","end":"2026-03-31T22:20:11.135187Z","steps":["trace[349390690] 'range keys from in-memory index tree' (duration: 1.284979676s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:11.135248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:09.849928Z","time spent":"1.285310977s","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T22:20:11.137104Z","caller":"traceutil/trace.go:171","msg":"trace[1139252396] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.200454113s","start":"2026-03-31T22:20:09.93662Z","end":"2026-03-31T22:20:11.137075Z","steps":["trace[1139252396] 'process raft request' (duration: 1.162171424s)","trace[1139252396] 'compare' (duration: 37.055119ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:20:11.137231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:09.936588Z","time spent":"1.200564636s","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:20:11.137253Z","caller":"traceutil/trace.go:171","msg":"trace[1489579480] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.132161899s","start":"2026-03-31T22:20:10.00506Z","end":"2026-03-31T22:20:11.137222Z","steps":["trace[1489579480] 'process raft request' (duration: 1.131941932s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:11.138518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:10.005039Z","time spent":"1.132324085s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:20:11.1629Z","caller":"traceutil/trace.go:171","msg":"trace[2079622265] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:595; }","duration":"669.679591ms","start":"2026-03-31T22:20:10.493175Z","end":"2026-03-31T22:20:11.162854Z","steps":["trace[2079622265] 'read index received' (duration: 605.46085ms)","trace[2079622265] 'applied index is now lower than readState.Index' (duration: 64.216851ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:20:11.163647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.378564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T22:20:11.163686Z","caller":"traceutil/trace.go:171","msg":"trace[489876414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"670.55521ms","start":"2026-03-31T22:20:10.493119Z","end":"2026-03-31T22:20:11.163674Z","steps":["trace[489876414] 'agreement among raft nodes before linearized reading' (duration: 670.274061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:11.163718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.878026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-31T22:20:11.163788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:10.493044Z","time spent":"670.733265ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T22:20:11.163885Z","caller":"traceutil/trace.go:171","msg":"trace[965393437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"635.99198ms","start":"2026-03-31T22:20:10.527794Z","end":"2026-03-31T22:20:11.163786Z","steps":["trace[965393437] 'agreement among raft nodes before linearized reading' (duration: 635.777933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:11.163939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:10.527787Z","time spent":"636.140425ms","remote":"127.0.0.1:56278","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-03-31T22:20:11.164098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.441085ms","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-03-31T22:20:11.16418Z","caller":"traceutil/trace.go:171","msg":"trace[756515086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"636.587039ms","start":"2026-03-31T22:20:10.527576Z","end":"2026-03-31T22:20:11.164163Z","steps":["trace[756515086] 'agreement among raft nodes before linearized reading' (duration: 635.99382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:11.164291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:10.527553Z","time spent":"636.682093ms","remote":"127.0.0.1:56278","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-03-31T22:20:12.280056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794459064775906,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T22:20:12.355217Z","caller":"traceutil/trace.go:171","msg":"trace[361139045] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"584.158066ms","start":"2026-03-31T22:20:11.771034Z","end":"2026-03-31T22:20:12.355192Z","steps":["trace[361139045] 'read index received' (duration: 583.428762ms)","trace[361139045] 'applied index is now lower than readState.Index' (duration: 728.034µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:20:12.35534Z","caller":"traceutil/trace.go:171","msg":"trace[381205993] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"741.8679ms","start":"2026-03-31T22:20:11.613463Z","end":"2026-03-31T22:20:12.355331Z","steps":["trace[381205993] 'process raft request' (duration: 741.135327ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.355493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.613428Z","time spent":"741.939092ms","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:20:12.355738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.473481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:20:12.355762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.938315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:12.355845Z","caller":"traceutil/trace.go:171","msg":"trace[1171042996] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"131.596926ms","start":"2026-03-31T22:20:12.224217Z","end":"2026-03-31T22:20:12.355814Z","steps":["trace[1171042996] 'agreement among raft nodes before linearized reading' (duration: 131.446141ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:12.355857Z","caller":"traceutil/trace.go:171","msg":"trace[966573167] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"103.08597ms","start":"2026-03-31T22:20:12.25275Z","end":"2026-03-31T22:20:12.355836Z","steps":["trace[966573167] 'agreement among raft nodes before linearized reading' (duration: 102.877114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.355971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.918621ms","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-03-31T22:20:12.356071Z","caller":"traceutil/trace.go:171","msg":"trace[1262094157] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:586; }","duration":"585.058005ms","start":"2026-03-31T22:20:11.770995Z","end":"2026-03-31T22:20:12.356053Z","steps":["trace[1262094157] 'agreement among raft nodes before linearized reading' (duration: 584.922241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.356102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.77098Z","time spent":"585.115327ms","remote":"127.0.0.1:49682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T22:20:12.356203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.196882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:20:12.356221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.154294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:12.356263Z","caller":"traceutil/trace.go:171","msg":"trace[384739543] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"104.199986ms","start":"2026-03-31T22:20:12.252051Z","end":"2026-03-31T22:20:12.356251Z","steps":["trace[384739543] 'agreement among raft nodes before linearized reading' (duration: 104.131133ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:12.356289Z","caller":"traceutil/trace.go:171","msg":"trace[1691003956] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"534.295345ms","start":"2026-03-31T22:20:11.821949Z","end":"2026-03-31T22:20:12.356245Z","steps":["trace[1691003956] 'agreement among raft nodes before linearized reading' (duration: 534.174002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.356362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.821943Z","time spent":"534.377088ms","remote":"127.0.0.1:49698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-03-31T22:20:12.356419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.494825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:12.356488Z","caller":"traceutil/trace.go:171","msg":"trace[558529505] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"104.581518ms","start":"2026-03-31T22:20:12.251897Z","end":"2026-03-31T22:20:12.356479Z","steps":["trace[558529505] 'agreement among raft nodes before linearized reading' (duration: 104.493505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.356603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.35286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:12.356652Z","caller":"traceutil/trace.go:171","msg":"trace[871797138] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"132.422502ms","start":"2026-03-31T22:20:12.224217Z","end":"2026-03-31T22:20:12.35664Z","steps":["trace[871797138] 'agreement among raft nodes before linearized reading' (duration: 132.35465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.356726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.201189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:20:12.356777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.189215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:12.356808Z","caller":"traceutil/trace.go:171","msg":"trace[2055672099] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:586; }","duration":"493.236496ms","start":"2026-03-31T22:20:11.863562Z","end":"2026-03-31T22:20:12.356799Z","steps":["trace[2055672099] 'agreement among raft nodes before linearized reading' (duration: 493.191495ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:12.356805Z","caller":"traceutil/trace.go:171","msg":"trace[205414690] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:586; }","duration":"286.336834ms","start":"2026-03-31T22:20:12.070455Z","end":"2026-03-31T22:20:12.356792Z","steps":["trace[205414690] 'agreement among raft nodes before linearized reading' (duration: 286.20509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.356825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.863539Z","time spent":"493.281328ms","remote":"127.0.0.1:49706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-03-31T22:20:12.356848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.132485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:12.356893Z","caller":"traceutil/trace.go:171","msg":"trace[1895894390] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:586; }","duration":"442.229559ms","start":"2026-03-31T22:20:11.914653Z","end":"2026-03-31T22:20:12.356882Z","steps":["trace[1895894390] 'agreement among raft nodes before linearized reading' (duration: 442.160017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.356932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.914625Z","time spent":"442.296171ms","remote":"127.0.0.1:49718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:20:12.356965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.115888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:20:12.356908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.477887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:12.356988Z","caller":"traceutil/trace.go:171","msg":"trace[2068010487] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:586; }","duration":"557.16826ms","start":"2026-03-31T22:20:11.799812Z","end":"2026-03-31T22:20:12.35698Z","steps":["trace[2068010487] 'agreement among raft nodes before linearized reading' (duration: 557.136039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.357005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.799795Z","time spent":"557.206561ms","remote":"127.0.0.1:49684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"info","ts":"2026-03-31T22:20:12.357003Z","caller":"traceutil/trace.go:171","msg":"trace[989474746] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:586; }","duration":"442.613671ms","start":"2026-03-31T22:20:11.914379Z","end":"2026-03-31T22:20:12.356993Z","steps":["trace[989474746] 'agreement among raft nodes before linearized reading' (duration: 442.495938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.35704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.914361Z","time spent":"442.669073ms","remote":"127.0.0.1:49718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T22:20:12.357101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.598387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:12.357128Z","caller":"traceutil/trace.go:171","msg":"trace[1994179839] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"535.637119ms","start":"2026-03-31T22:20:11.821483Z","end":"2026-03-31T22:20:12.357121Z","steps":["trace[1994179839] 'agreement among raft nodes before linearized reading' (duration: 535.594437ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.357143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.821477Z","time spent":"535.662849ms","remote":"127.0.0.1:49698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:20:12.357068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.618763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:12.357185Z","caller":"traceutil/trace.go:171","msg":"trace[520060848] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:586; }","duration":"495.760177ms","start":"2026-03-31T22:20:11.861412Z","end":"2026-03-31T22:20:12.357172Z","steps":["trace[520060848] 'agreement among raft nodes before linearized reading' (duration: 495.595552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.357225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.357638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c6ml2\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-03-31T22:20:12.357244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.202498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:20:12.357258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.861408Z","time spent":"495.836461ms","remote":"127.0.0.1:49706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-03-31T22:20:12.357289Z","caller":"traceutil/trace.go:171","msg":"trace[564729239] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:586; }","duration":"556.23709ms","start":"2026-03-31T22:20:11.801021Z","end":"2026-03-31T22:20:12.357258Z","steps":["trace[564729239] 'agreement among raft nodes before linearized reading' (duration: 556.212699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.357313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.801013Z","time spent":"556.296352ms","remote":"127.0.0.1:49684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-03-31T22:20:12.357307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.533851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:20:12.35733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.149427ms","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":"info","ts":"2026-03-31T22:20:12.357346Z","caller":"traceutil/trace.go:171","msg":"trace[1016386432] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:586; }","duration":"557.576052ms","start":"2026-03-31T22:20:11.799758Z","end":"2026-03-31T22:20:12.357334Z","steps":["trace[1016386432] 'agreement among raft nodes before linearized reading' (duration: 557.526101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.357097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.900088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:12.357375Z","caller":"traceutil/trace.go:171","msg":"trace[590925460] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:586; }","duration":"167.219279ms","start":"2026-03-31T22:20:12.190145Z","end":"2026-03-31T22:20:12.357364Z","steps":["trace[590925460] 'agreement among raft nodes before linearized reading' (duration: 167.151907ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.35738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.799753Z","time spent":"557.618884ms","remote":"127.0.0.1:49684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-03-31T22:20:12.357389Z","caller":"traceutil/trace.go:171","msg":"trace[877478857] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:586; }","duration":"442.235249ms","start":"2026-03-31T22:20:11.915148Z","end":"2026-03-31T22:20:12.357383Z","steps":["trace[877478857] 'agreement among raft nodes before linearized reading' (duration: 441.922059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.357514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.915129Z","time spent":"442.375794ms","remote":"127.0.0.1:49718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-03-31T22:20:12.357515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.105719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:12.357587Z","caller":"traceutil/trace.go:171","msg":"trace[651399719] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:586; }","duration":"496.203332ms","start":"2026-03-31T22:20:11.861372Z","end":"2026-03-31T22:20:12.357575Z","steps":["trace[651399719] 'agreement among raft nodes before linearized reading' (duration: 496.082648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.357224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.861576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:12.357641Z","caller":"traceutil/trace.go:171","msg":"trace[1455085063] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"536.297069ms","start":"2026-03-31T22:20:11.821338Z","end":"2026-03-31T22:20:12.357636Z","steps":["trace[1455085063] 'agreement among raft nodes before linearized reading' (duration: 535.869615ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.357665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.821327Z","time spent":"536.332781ms","remote":"127.0.0.1:49698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T22:20:12.35766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.350317ms","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-03-31T22:20:12.357711Z","caller":"traceutil/trace.go:171","msg":"trace[51365518] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:586; }","duration":"586.406258ms","start":"2026-03-31T22:20:11.771293Z","end":"2026-03-31T22:20:12.357699Z","steps":["trace[51365518] 'agreement among raft nodes before linearized reading' (duration: 586.342566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.357709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.602961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:20:12.357749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.771289Z","time spent":"586.451629ms","remote":"127.0.0.1:49682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"info","ts":"2026-03-31T22:20:12.357759Z","caller":"traceutil/trace.go:171","msg":"trace[1350770603] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:586; }","duration":"167.682004ms","start":"2026-03-31T22:20:12.190064Z","end":"2026-03-31T22:20:12.357746Z","steps":["trace[1350770603] 'agreement among raft nodes before linearized reading' (duration: 167.602301ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:12.357264Z","caller":"traceutil/trace.go:171","msg":"trace[1556956296] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c6ml2; range_end:; response_count:1; response_revision:586; }","duration":"558.41087ms","start":"2026-03-31T22:20:11.798843Z","end":"2026-03-31T22:20:12.357254Z","steps":["trace[1556956296] 'agreement among raft nodes before linearized reading' (duration: 558.281046ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.357852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.798821Z","time spent":"559.023009ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-c6ml2\" "} {"level":"warn","ts":"2026-03-31T22:20:12.35753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.477575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:12.357908Z","caller":"traceutil/trace.go:171","msg":"trace[1084199026] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:586; }","duration":"166.893488ms","start":"2026-03-31T22:20:12.191004Z","end":"2026-03-31T22:20:12.357898Z","steps":["trace[1084199026] 'agreement among raft nodes before linearized reading' (duration: 166.501076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.357664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.861358Z","time spent":"496.261264ms","remote":"127.0.0.1:49706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:20:12.35806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.501511ms","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":"info","ts":"2026-03-31T22:20:12.358094Z","caller":"traceutil/trace.go:171","msg":"trace[821708900] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:586; }","duration":"287.585953ms","start":"2026-03-31T22:20:12.070496Z","end":"2026-03-31T22:20:12.358082Z","steps":["trace[821708900] 'agreement among raft nodes before linearized reading' (duration: 285.095853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.358128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.342242ms","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-03-31T22:20:12.358226Z","caller":"traceutil/trace.go:171","msg":"trace[1144079453] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:586; }","duration":"586.976407ms","start":"2026-03-31T22:20:11.771237Z","end":"2026-03-31T22:20:12.358214Z","steps":["trace[1144079453] 'agreement among raft nodes before linearized reading' (duration: 584.338072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:12.358285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:11.771232Z","time spent":"587.042189ms","remote":"127.0.0.1:49682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:20:14.948019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.437484022s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:20:14.948071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:14.619785Z","time spent":"328.278949ms","remote":"127.0.0.1:47098","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-31T22:20:14.948103Z","caller":"traceutil/trace.go:171","msg":"trace[1150199442] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:588; }","duration":"2.437626117s","start":"2026-03-31T22:20:12.510457Z","end":"2026-03-31T22:20:14.948084Z","steps":["trace[1150199442] 'range keys from in-memory index tree' (duration: 2.437414669s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:14.948312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.096504155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:14.948412Z","caller":"traceutil/trace.go:171","msg":"trace[2127580430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"2.096664199s","start":"2026-03-31T22:20:12.851723Z","end":"2026-03-31T22:20:14.948388Z","steps":["trace[2127580430] 'range keys from in-memory index tree' (duration: 2.096431262s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:14.948468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:12.851701Z","time spent":"2.096756793s","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T22:20:14.949203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.764289049s","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-03-31T22:20:14.949324Z","caller":"traceutil/trace.go:171","msg":"trace[2072553627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"1.764445484s","start":"2026-03-31T22:20:13.184862Z","end":"2026-03-31T22:20:14.949307Z","steps":["trace[2072553627] 'range keys from in-memory index tree' (duration: 1.764107153s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:14.948142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:12.510446Z","time spent":"2.437687378s","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-03-31T22:20:14.949436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:13.18485Z","time spent":"1.764573559s","remote":"127.0.0.1:56278","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-03-31T22:20:14.949685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770767157s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-31T22:20:14.949723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.783137094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T22:20:14.949786Z","caller":"traceutil/trace.go:171","msg":"trace[1828236714] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"1.783224587s","start":"2026-03-31T22:20:13.166541Z","end":"2026-03-31T22:20:14.949766Z","steps":["trace[1828236714] 'range keys from in-memory index tree' (duration: 1.782944238s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:14.949826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:13.166521Z","time spent":"1.783297779s","remote":"127.0.0.1:56278","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-03-31T22:20:14.94973Z","caller":"traceutil/trace.go:171","msg":"trace[1118753196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"1.770844499s","start":"2026-03-31T22:20:13.178873Z","end":"2026-03-31T22:20:14.949717Z","steps":["trace[1118753196] 'range keys from in-memory index tree' (duration: 1.770626503s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:14.949886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:13.178851Z","time spent":"1.771022165s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T22:20:14.949971Z","caller":"traceutil/trace.go:171","msg":"trace[42360845] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"450.352579ms","start":"2026-03-31T22:20:14.499594Z","end":"2026-03-31T22:20:14.949947Z","steps":["trace[42360845] 'process raft request' (duration: 391.614594ms)","trace[42360845] 'compare' (duration: 57.481515ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:20:14.950116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:14.49957Z","time spent":"450.461453ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:20:14.949308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.768855646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T22:20:14.951738Z","caller":"traceutil/trace.go:171","msg":"trace[453766327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"1.771354925s","start":"2026-03-31T22:20:13.180367Z","end":"2026-03-31T22:20:14.951722Z","steps":["trace[453766327] 'range keys from in-memory index tree' (duration: 1.767771271s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:14.951778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:13.180349Z","time spent":"1.771418368s","remote":"127.0.0.1:56278","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-03-31T22:20:17.124398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.707769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T22:20:17.124469Z","caller":"traceutil/trace.go:171","msg":"trace[281362030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"112.823912ms","start":"2026-03-31T22:20:17.011631Z","end":"2026-03-31T22:20:17.124455Z","steps":["trace[281362030] 'range keys from in-memory index tree' (duration: 112.545413ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:17.127078Z","caller":"traceutil/trace.go:171","msg":"trace[73460516] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"110.033573ms","start":"2026-03-31T22:20:17.017018Z","end":"2026-03-31T22:20:17.127052Z","steps":["trace[73460516] 'process raft request' (duration: 109.814866ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:20.172291Z","caller":"traceutil/trace.go:171","msg":"trace[1974770519] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"119.859668ms","start":"2026-03-31T22:20:20.052407Z","end":"2026-03-31T22:20:20.172267Z","steps":["trace[1974770519] 'process raft request' (duration: 119.679292ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:31.360574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.647814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:31.36067Z","caller":"traceutil/trace.go:171","msg":"trace[1443411505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"509.890542ms","start":"2026-03-31T22:20:30.85076Z","end":"2026-03-31T22:20:31.360651Z","steps":["trace[1443411505] 'range keys from in-memory index tree' (duration: 509.554041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:31.36071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:30.850661Z","time spent":"510.040267ms","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T22:20:31.361613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.533076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T22:20:31.361766Z","caller":"traceutil/trace.go:171","msg":"trace[220929307] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:752; }","duration":"692.731242ms","start":"2026-03-31T22:20:30.669017Z","end":"2026-03-31T22:20:31.361748Z","steps":["trace[220929307] 'range keys from in-memory index tree' (duration: 692.3278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:31.361808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:30.668996Z","time spent":"692.802095ms","remote":"127.0.0.1:56278","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-03-31T22:20:31.364311Z","caller":"traceutil/trace.go:171","msg":"trace[736498038] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"444.585165ms","start":"2026-03-31T22:20:30.919704Z","end":"2026-03-31T22:20:31.36429Z","steps":["trace[736498038] 'process raft request' (duration: 444.42722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:31.364416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:30.919678Z","time spent":"444.684428ms","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:20:31.364517Z","caller":"traceutil/trace.go:171","msg":"trace[284450441] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"163.316284ms","start":"2026-03-31T22:20:31.200936Z","end":"2026-03-31T22:20:31.364252Z","steps":["trace[284450441] 'read index received' (duration: 163.117747ms)","trace[284450441] 'applied index is now lower than readState.Index' (duration: 197.867µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:20:31.364644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.707936ms","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-03-31T22:20:31.36467Z","caller":"traceutil/trace.go:171","msg":"trace[1204354704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:753; }","duration":"163.766868ms","start":"2026-03-31T22:20:31.200897Z","end":"2026-03-31T22:20:31.364663Z","steps":["trace[1204354704] 'agreement among raft nodes before linearized reading' (duration: 163.670415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:31.365001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.041176ms","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-03-31T22:20:31.365029Z","caller":"traceutil/trace.go:171","msg":"trace[954383202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"154.104218ms","start":"2026-03-31T22:20:31.210916Z","end":"2026-03-31T22:20:31.36502Z","steps":["trace[954383202] 'agreement among raft nodes before linearized reading' (duration: 154.031036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:31.365113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.006576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T22:20:31.365166Z","caller":"traceutil/trace.go:171","msg":"trace[931591897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"164.11328ms","start":"2026-03-31T22:20:31.201041Z","end":"2026-03-31T22:20:31.365154Z","steps":["trace[931591897] 'agreement among raft nodes before linearized reading' (duration: 163.908633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:31.365405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.84972ms","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-03-31T22:20:31.365445Z","caller":"traceutil/trace.go:171","msg":"trace[795353999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"163.927934ms","start":"2026-03-31T22:20:31.201507Z","end":"2026-03-31T22:20:31.365435Z","steps":["trace[795353999] 'agreement among raft nodes before linearized reading' (duration: 163.837641ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:55.494685Z","caller":"traceutil/trace.go:171","msg":"trace[589990641] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"320.095436ms","start":"2026-03-31T22:20:55.174546Z","end":"2026-03-31T22:20:55.494642Z","steps":["trace[589990641] 'process raft request' (duration: 319.888039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:55.495004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:55.174527Z","time spent":"320.374375ms","remote":"127.0.0.1:55992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:20:55.782171Z","caller":"traceutil/trace.go:171","msg":"trace[1750020468] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"172.798194ms","start":"2026-03-31T22:20:55.60935Z","end":"2026-03-31T22:20:55.782148Z","steps":["trace[1750020468] 'read index received' (duration: 144.723841ms)","trace[1750020468] 'applied index is now lower than readState.Index' (duration: 28.073522ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:20:55.782242Z","caller":"traceutil/trace.go:171","msg":"trace[2048540583] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"190.264448ms","start":"2026-03-31T22:20:55.591934Z","end":"2026-03-31T22:20:55.782198Z","steps":["trace[2048540583] 'process raft request' (duration: 162.33698ms)","trace[2048540583] 'compare' (duration: 27.738132ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:20:55.782436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.382865ms","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-03-31T22:20:55.782398Z","caller":"traceutil/trace.go:171","msg":"trace[1137704742] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"146.974903ms","start":"2026-03-31T22:20:55.635395Z","end":"2026-03-31T22:20:55.78237Z","steps":["trace[1137704742] 'process raft request' (duration: 146.722355ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:55.782496Z","caller":"traceutil/trace.go:171","msg":"trace[1492300177] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:931; }","duration":"197.475608ms","start":"2026-03-31T22:20:55.585007Z","end":"2026-03-31T22:20:55.782482Z","steps":["trace[1492300177] 'agreement among raft nodes before linearized reading' (duration: 197.20573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:55.782561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.644823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T22:20:55.782643Z","caller":"traceutil/trace.go:171","msg":"trace[357783021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:931; }","duration":"197.800338ms","start":"2026-03-31T22:20:55.584827Z","end":"2026-03-31T22:20:55.782627Z","steps":["trace[357783021] 'agreement among raft nodes before linearized reading' (duration: 197.444256ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:56.161552Z","caller":"traceutil/trace.go:171","msg":"trace[473901974] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:964; }","duration":"311.395599ms","start":"2026-03-31T22:20:55.850133Z","end":"2026-03-31T22:20:56.161529Z","steps":["trace[473901974] 'read index received' (duration: 311.11175ms)","trace[473901974] 'applied index is now lower than readState.Index' (duration: 282.819µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:20:56.161657Z","caller":"traceutil/trace.go:171","msg":"trace[933371893] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"374.002649ms","start":"2026-03-31T22:20:55.787618Z","end":"2026-03-31T22:20:56.16162Z","steps":["trace[933371893] 'process raft request' (duration: 373.870885ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:56.161616Z","caller":"traceutil/trace.go:171","msg":"trace[1846865964] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"373.962108ms","start":"2026-03-31T22:20:55.787606Z","end":"2026-03-31T22:20:56.161568Z","steps":["trace[1846865964] 'process raft request' (duration: 373.687619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:56.161758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.604736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:56.161847Z","caller":"traceutil/trace.go:171","msg":"trace[423574284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"311.73516ms","start":"2026-03-31T22:20:55.850085Z","end":"2026-03-31T22:20:56.16182Z","steps":["trace[423574284] 'agreement among raft nodes before linearized reading' (duration: 311.586055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:56.16185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:55.7876Z","time spent":"374.141923ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:20:56.161904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:55.850066Z","time spent":"311.828943ms","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T22:20:56.161994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:55.787587Z","time spent":"374.240757ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:20:56.392068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.36038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T22:20:56.392316Z","caller":"traceutil/trace.go:171","msg":"trace[2051433472] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"205.169362ms","start":"2026-03-31T22:20:56.187117Z","end":"2026-03-31T22:20:56.392287Z","steps":["trace[2051433472] 'process raft request' (duration: 54.42406ms)","trace[2051433472] 'compare' (duration: 150.165284ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:20:57.793574Z","caller":"traceutil/trace.go:171","msg":"trace[1694767549] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"176.594794ms","start":"2026-03-31T22:20:57.616938Z","end":"2026-03-31T22:20:57.793533Z","steps":["trace[1694767549] 'process raft request' (duration: 176.437288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:58.143157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.31894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T22:20:58.143255Z","caller":"traceutil/trace.go:171","msg":"trace[208411941] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"327.377208ms","start":"2026-03-31T22:20:57.815861Z","end":"2026-03-31T22:20:58.143238Z","steps":["trace[208411941] 'read index received' (duration: 58.910603ms)","trace[208411941] 'applied index is now lower than readState.Index' (duration: 268.465394ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:20:58.143348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.563182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:20:58.143375Z","caller":"traceutil/trace.go:171","msg":"trace[1853031864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"293.609844ms","start":"2026-03-31T22:20:57.849755Z","end":"2026-03-31T22:20:58.143365Z","steps":["trace[1853031864] 'agreement among raft nodes before linearized reading' (duration: 293.564062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:58.143414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.544863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T22:20:58.143455Z","caller":"traceutil/trace.go:171","msg":"trace[37564503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:937; }","duration":"327.606225ms","start":"2026-03-31T22:20:57.815838Z","end":"2026-03-31T22:20:58.143444Z","steps":["trace[37564503] 'agreement among raft nodes before linearized reading' (duration: 327.472731ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:20:58.143498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:57.815829Z","time spent":"327.659947ms","remote":"127.0.0.1:56278","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-03-31T22:20:58.143803Z","caller":"traceutil/trace.go:171","msg":"trace[1853371774] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"346.312499ms","start":"2026-03-31T22:20:57.797449Z","end":"2026-03-31T22:20:58.143761Z","steps":["trace[1853371774] 'process raft request' (duration: 77.297007ms)","trace[1853371774] 'compare' (duration: 268.085463ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:20:58.143959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:20:57.797425Z","time spent":"346.439423ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:20:58.386169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.390768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T22:20:58.386267Z","caller":"traceutil/trace.go:171","msg":"trace[1604297682] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"220.546561ms","start":"2026-03-31T22:20:58.165704Z","end":"2026-03-31T22:20:58.386251Z","steps":["trace[1604297682] 'read index received' (duration: 104.911145ms)","trace[1604297682] 'applied index is now lower than readState.Index' (duration: 115.634546ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:20:58.386348Z","caller":"traceutil/trace.go:171","msg":"trace[1581020418] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"239.001127ms","start":"2026-03-31T22:20:58.147336Z","end":"2026-03-31T22:20:58.386337Z","steps":["trace[1581020418] 'process raft request' (duration: 123.357581ms)","trace[1581020418] 'compare' (duration: 115.283605ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:20:58.386589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.874622ms","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-03-31T22:20:58.386607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.412507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T22:20:58.386649Z","caller":"traceutil/trace.go:171","msg":"trace[1126550160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:938; }","duration":"220.952704ms","start":"2026-03-31T22:20:58.165682Z","end":"2026-03-31T22:20:58.386634Z","steps":["trace[1126550160] 'agreement among raft nodes before linearized reading' (duration: 220.763858ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:20:58.386682Z","caller":"traceutil/trace.go:171","msg":"trace[1017171528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:938; }","duration":"220.547881ms","start":"2026-03-31T22:20:58.166116Z","end":"2026-03-31T22:20:58.386663Z","steps":["trace[1017171528] 'agreement among raft nodes before linearized reading' (duration: 220.310353ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:05.190407Z","caller":"traceutil/trace.go:171","msg":"trace[271960294] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"148.49999ms","start":"2026-03-31T22:21:05.041886Z","end":"2026-03-31T22:21:05.190386Z","steps":["trace[271960294] 'process raft request' (duration: 128.071401ms)","trace[271960294] 'compare' (duration: 20.282285ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:21:10.523169Z","caller":"traceutil/trace.go:171","msg":"trace[1758790833] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"307.077821ms","start":"2026-03-31T22:21:10.216064Z","end":"2026-03-31T22:21:10.523142Z","steps":["trace[1758790833] 'process raft request' (duration: 306.906246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:10.523364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:10.216044Z","time spent":"307.226277ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:21:11.750039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150641867s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-31T22:21:11.750636Z","caller":"traceutil/trace.go:171","msg":"trace[132789856] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1015; }","duration":"1.203947702s","start":"2026-03-31T22:21:10.546488Z","end":"2026-03-31T22:21:11.750435Z","steps":["trace[132789856] 'read index received' (duration: 52.947283ms)","trace[132789856] 'applied index is now lower than readState.Index' (duration: 1.150998859s)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:21:11.750676Z","caller":"traceutil/trace.go:171","msg":"trace[1777208101] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.209194899s","start":"2026-03-31T22:21:10.541453Z","end":"2026-03-31T22:21:11.750648Z","steps":["trace[1777208101] 'process raft request' (duration: 1.208709133s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:11.750804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:10.54143Z","time spent":"1.209308243s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:21:11.953403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392897449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-31T22:21:11.953401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.268633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:21:11.953469Z","caller":"traceutil/trace.go:171","msg":"trace[650252878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:981; }","duration":"1.393000123s","start":"2026-03-31T22:21:10.560453Z","end":"2026-03-31T22:21:11.953454Z","steps":["trace[650252878] 'agreement among raft nodes before linearized reading' (duration: 1.19034287s)","trace[650252878] 'range keys from in-memory index tree' (duration: 202.491317ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:21:11.953462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406961355s","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-03-31T22:21:11.953498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:10.560443Z","time spent":"1.393049194s","remote":"127.0.0.1:56278","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-03-31T22:21:11.953503Z","caller":"traceutil/trace.go:171","msg":"trace[645543338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"103.389926ms","start":"2026-03-31T22:21:11.850083Z","end":"2026-03-31T22:21:11.953473Z","steps":["trace[645543338] 'range keys from in-memory index tree' (duration: 103.17781ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:11.953515Z","caller":"traceutil/trace.go:171","msg":"trace[817529402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:981; }","duration":"1.407041298s","start":"2026-03-31T22:21:10.546457Z","end":"2026-03-31T22:21:11.953498Z","steps":["trace[817529402] 'agreement among raft nodes before linearized reading' (duration: 1.204260582s)","trace[817529402] 'range keys from in-memory index tree' (duration: 202.603061ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:21:11.95342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195166253s","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-03-31T22:21:11.953553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:10.546443Z","time spent":"1.407101281s","remote":"127.0.0.1:56278","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":"info","ts":"2026-03-31T22:21:11.953566Z","caller":"traceutil/trace.go:171","msg":"trace[1779870226] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:981; }","duration":"1.195353129s","start":"2026-03-31T22:21:10.758196Z","end":"2026-03-31T22:21:11.953549Z","steps":["trace[1779870226] 'agreement among raft nodes before linearized reading' (duration: 992.588423ms)","trace[1779870226] 'count revisions from in-memory index tree' (duration: 202.605221ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:21:11.95361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:10.758171Z","time spent":"1.195424431s","remote":"127.0.0.1:56138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:21:11.95365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3941864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T22:21:11.953725Z","caller":"traceutil/trace.go:171","msg":"trace[1248286202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:981; }","duration":"1.394316864s","start":"2026-03-31T22:21:10.559384Z","end":"2026-03-31T22:21:11.953701Z","steps":["trace[1248286202] 'agreement among raft nodes before linearized reading' (duration: 1.191401753s)","trace[1248286202] 'range keys from in-memory index tree' (duration: 202.596051ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:21:11.953766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:10.559352Z","time spent":"1.394405657s","remote":"127.0.0.1:56278","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-03-31T22:21:12.315399Z","caller":"traceutil/trace.go:171","msg":"trace[555411318] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"358.60434ms","start":"2026-03-31T22:21:11.956774Z","end":"2026-03-31T22:21:12.315378Z","steps":["trace[555411318] 'process raft request' (duration: 358.497207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:12.31554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:11.956752Z","time spent":"358.703383ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:12.320652Z","caller":"traceutil/trace.go:171","msg":"trace[970294510] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"363.525316ms","start":"2026-03-31T22:21:11.957109Z","end":"2026-03-31T22:21:12.320634Z","steps":["trace[970294510] 'process raft request' (duration: 363.404872ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:12.320783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:11.957101Z","time spent":"363.607879ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:12.320887Z","caller":"traceutil/trace.go:171","msg":"trace[1070188182] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"363.374032ms","start":"2026-03-31T22:21:11.957507Z","end":"2026-03-31T22:21:12.320881Z","steps":["trace[1070188182] 'process raft request' (duration: 363.094493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:12.320926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:11.957486Z","time spent":"363.420873ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:12.320989Z","caller":"traceutil/trace.go:171","msg":"trace[138320589] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"362.846065ms","start":"2026-03-31T22:21:11.958127Z","end":"2026-03-31T22:21:12.320973Z","steps":["trace[138320589] 'process raft request' (duration: 362.652469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:12.321075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:11.958107Z","time spent":"362.920257ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:25.196799Z","caller":"traceutil/trace.go:171","msg":"trace[547201771] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"156.448213ms","start":"2026-03-31T22:21:25.040312Z","end":"2026-03-31T22:21:25.19676Z","steps":["trace[547201771] 'process raft request' (duration: 113.369804ms)","trace[547201771] 'compare' (duration: 42.666856ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:21:34.305444Z","caller":"traceutil/trace.go:171","msg":"trace[868013144] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"383.044027ms","start":"2026-03-31T22:21:33.922373Z","end":"2026-03-31T22:21:34.305417Z","steps":["trace[868013144] 'process raft request' (duration: 382.886182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:34.305692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:33.922349Z","time spent":"383.183011ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:21:36.333572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.647436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:21:36.33365Z","caller":"traceutil/trace.go:171","msg":"trace[1738083380] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1054; }","duration":"187.78409ms","start":"2026-03-31T22:21:36.145847Z","end":"2026-03-31T22:21:36.333631Z","steps":["trace[1738083380] 'count revisions from in-memory index tree' (duration: 187.571732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.334078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483087666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:21:36.334148Z","caller":"traceutil/trace.go:171","msg":"trace[879152808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1054; }","duration":"1.483190899s","start":"2026-03-31T22:21:34.850941Z","end":"2026-03-31T22:21:36.334132Z","steps":["trace[879152808] 'range keys from in-memory index tree' (duration: 1.482995092s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.334185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:34.850921Z","time spent":"1.483251141s","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T22:21:36.334199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.912691093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T22:21:36.334264Z","caller":"traceutil/trace.go:171","msg":"trace[145230829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1054; }","duration":"1.912759145s","start":"2026-03-31T22:21:34.421481Z","end":"2026-03-31T22:21:36.33424Z","steps":["trace[145230829] 'range keys from in-memory index tree' (duration: 1.91259541s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.334316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:34.421472Z","time spent":"1.912831117s","remote":"127.0.0.1:56278","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-03-31T22:21:36.334057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.91700738s","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-03-31T22:21:36.33472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.911899308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T22:21:36.334763Z","caller":"traceutil/trace.go:171","msg":"trace[822906597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"1.917762474s","start":"2026-03-31T22:21:34.416982Z","end":"2026-03-31T22:21:36.334744Z","steps":["trace[822906597] 'range keys from in-memory index tree' (duration: 1.916801703s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.334778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710269578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T22:21:36.33479Z","caller":"traceutil/trace.go:171","msg":"trace[1705243725] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1054; }","duration":"1.912019461s","start":"2026-03-31T22:21:34.422753Z","end":"2026-03-31T22:21:36.334772Z","steps":["trace[1705243725] 'range keys from in-memory index tree' (duration: 1.911728792s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.33481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:34.41695Z","time spent":"1.917846877s","remote":"127.0.0.1:56278","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-03-31T22:21:36.334806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342575469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.73\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-31T22:21:36.334827Z","caller":"traceutil/trace.go:171","msg":"trace[657876919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1054; }","duration":"1.71035507s","start":"2026-03-31T22:21:34.624459Z","end":"2026-03-31T22:21:36.334814Z","steps":["trace[657876919] 'range keys from in-memory index tree' (duration: 1.710113892s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.334833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:34.422732Z","time spent":"1.912092014s","remote":"127.0.0.1:56278","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-03-31T22:21:36.334865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:34.62444Z","time spent":"1.710415482s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T22:21:36.334866Z","caller":"traceutil/trace.go:171","msg":"trace[352296729] range","detail":"{range_begin:/registry/masterleases/199.19.213.73; range_end:; response_count:1; response_revision:1054; }","duration":"1.34263248s","start":"2026-03-31T22:21:34.992214Z","end":"2026-03-31T22:21:36.334846Z","steps":["trace[352296729] 'range keys from in-memory index tree' (duration: 1.342387633s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.334896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.908129397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T22:21:36.334954Z","caller":"traceutil/trace.go:171","msg":"trace[1217615922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1054; }","duration":"1.90821223s","start":"2026-03-31T22:21:34.426728Z","end":"2026-03-31T22:21:36.33494Z","steps":["trace[1217615922] 'range keys from in-memory index tree' (duration: 1.908002784s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.335005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:34.426718Z","time spent":"1.908280872s","remote":"127.0.0.1:56278","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-03-31T22:21:36.334915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:34.992135Z","time spent":"1.342767985s","remote":"127.0.0.1:55992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.73\" "} {"level":"warn","ts":"2026-03-31T22:21:36.33516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.765862755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T22:21:36.335217Z","caller":"traceutil/trace.go:171","msg":"trace[1560071027] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1054; }","duration":"1.765975479s","start":"2026-03-31T22:21:34.569229Z","end":"2026-03-31T22:21:36.335204Z","steps":["trace[1560071027] 'count revisions from in-memory index tree' (duration: 1.765759761s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.335339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:34.569188Z","time spent":"1.766050941s","remote":"127.0.0.1:56056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":94,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-03-31T22:21:36.45141Z","caller":"traceutil/trace.go:171","msg":"trace[1996784730] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"157.848127ms","start":"2026-03-31T22:21:36.293541Z","end":"2026-03-31T22:21:36.45139Z","steps":["trace[1996784730] 'process raft request' (duration: 157.749434ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:36.886498Z","caller":"traceutil/trace.go:171","msg":"trace[36927888] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"576.052883ms","start":"2026-03-31T22:21:36.31042Z","end":"2026-03-31T22:21:36.886473Z","steps":["trace[36927888] 'process raft request' (duration: 575.861666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.88665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:36.310386Z","time spent":"576.178236ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:21:36.886785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:36.337081Z","time spent":"549.697355ms","remote":"127.0.0.1:55992","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-31T22:21:36.886925Z","caller":"traceutil/trace.go:171","msg":"trace[542855824] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"546.956757ms","start":"2026-03-31T22:21:36.339944Z","end":"2026-03-31T22:21:36.886901Z","steps":["trace[542855824] 'process raft request' (duration: 546.891605ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:36.886969Z","caller":"traceutil/trace.go:171","msg":"trace[1321546306] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"547.157044ms","start":"2026-03-31T22:21:36.339792Z","end":"2026-03-31T22:21:36.886949Z","steps":["trace[1321546306] 'process raft request' (duration: 547.006769ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.887058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:36.339768Z","time spent":"547.244867ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:21:36.88707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:36.339931Z","time spent":"547.061321ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:36.887179Z","caller":"traceutil/trace.go:171","msg":"trace[777972015] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"547.110653ms","start":"2026-03-31T22:21:36.340055Z","end":"2026-03-31T22:21:36.887166Z","steps":["trace[777972015] 'process raft request' (duration: 546.817523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.887226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:36.340044Z","time spent":"547.159774ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:36.887377Z","caller":"traceutil/trace.go:171","msg":"trace[1208023050] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1095; }","duration":"550.090667ms","start":"2026-03-31T22:21:36.337275Z","end":"2026-03-31T22:21:36.887365Z","steps":["trace[1208023050] 'read index received' (duration: 114.108407ms)","trace[1208023050] 'applied index is now lower than readState.Index' (duration: 435.98146ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:21:36.887399Z","caller":"traceutil/trace.go:171","msg":"trace[478926471] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"547.248887ms","start":"2026-03-31T22:21:36.340142Z","end":"2026-03-31T22:21:36.887391Z","steps":["trace[478926471] 'process raft request' (duration: 546.988798ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.88744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:36.340122Z","time spent":"547.295609ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:21:36.887447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.16634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:21:36.887483Z","caller":"traceutil/trace.go:171","msg":"trace[293248602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"550.220351ms","start":"2026-03-31T22:21:36.337253Z","end":"2026-03-31T22:21:36.887473Z","steps":["trace[293248602] 'agreement among raft nodes before linearized reading' (duration: 550.159329ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:36.886903Z","caller":"traceutil/trace.go:171","msg":"trace[1115702890] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"547.106592ms","start":"2026-03-31T22:21:36.339768Z","end":"2026-03-31T22:21:36.886875Z","steps":["trace[1115702890] 'process raft request' (duration: 546.931537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:36.887718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:36.339753Z","time spent":"547.886257ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:21:36.887517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:36.337242Z","time spent":"550.268783ms","remote":"127.0.0.1:55962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T22:21:39.608912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794459064776962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T22:21:40.11003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794459064776962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T22:21:40.254697Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.349403886s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T22:21:40.255192Z","caller":"traceutil/trace.go:171","msg":"trace[290360809] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"1.359131185s","start":"2026-03-31T22:21:38.896036Z","end":"2026-03-31T22:21:40.255168Z","steps":["trace[290360809] 'process raft request' (duration: 1.358913358s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:40.255339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:38.896013Z","time spent":"1.359250929s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:40.255482Z","caller":"traceutil/trace.go:171","msg":"trace[1553037299] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"1.359244489s","start":"2026-03-31T22:21:38.896215Z","end":"2026-03-31T22:21:40.255459Z","steps":["trace[1553037299] 'process raft request' (duration: 1.358915078s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:40.255595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:38.89618Z","time spent":"1.359342872s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:40.255864Z","caller":"traceutil/trace.go:171","msg":"trace[479754484] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"1.359604011s","start":"2026-03-31T22:21:38.896235Z","end":"2026-03-31T22:21:40.255839Z","steps":["trace[479754484] 'process raft request' (duration: 1.359153126s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:40.256018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:38.896215Z","time spent":"1.359705773s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:40.256032Z","caller":"traceutil/trace.go:171","msg":"trace[1898724815] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"1.358599408s","start":"2026-03-31T22:21:38.897419Z","end":"2026-03-31T22:21:40.256018Z","steps":["trace[1898724815] 'process raft request' (duration: 1.358224486s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:40.256135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:38.897405Z","time spent":"1.358681981s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:40.256734Z","caller":"traceutil/trace.go:171","msg":"trace[1089597645] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1104; }","duration":"1.148566241s","start":"2026-03-31T22:21:39.108147Z","end":"2026-03-31T22:21:40.256714Z","steps":["trace[1089597645] 'read index received' (duration: 1.146664571s)","trace[1089597645] 'applied index is now lower than readState.Index' (duration: 1.89958ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:21:40.25694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074532828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:21:40.256942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148782518s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T22:21:40.256952Z","caller":"traceutil/trace.go:171","msg":"trace[2130386210] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"1.359019491s","start":"2026-03-31T22:21:38.897912Z","end":"2026-03-31T22:21:40.256932Z","steps":["trace[2130386210] 'process raft request' (duration: 1.357906566s)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:40.257002Z","caller":"traceutil/trace.go:171","msg":"trace[389838812] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1068; }","duration":"1.074635481s","start":"2026-03-31T22:21:39.182349Z","end":"2026-03-31T22:21:40.256984Z","steps":["trace[389838812] 'agreement among raft nodes before linearized reading' (duration: 1.074535708s)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:40.257013Z","caller":"traceutil/trace.go:171","msg":"trace[406182384] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1068; }","duration":"1.148904002s","start":"2026-03-31T22:21:39.108093Z","end":"2026-03-31T22:21:40.256997Z","steps":["trace[406182384] 'agreement among raft nodes before linearized reading' (duration: 1.148773508s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:40.257022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.203133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:21:40.257051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:38.897818Z","time spent":"1.359190477s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:21:40.257058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:39.108071Z","time spent":"1.148976475s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:21:40.25705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:39.182331Z","time spent":"1.074705624s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-03-31T22:21:40.257064Z","caller":"traceutil/trace.go:171","msg":"trace[1956052879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1068; }","duration":"406.293776ms","start":"2026-03-31T22:21:39.850759Z","end":"2026-03-31T22:21:40.257052Z","steps":["trace[1956052879] 'agreement among raft nodes before linearized reading' (duration: 406.215284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:40.257103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:39.850689Z","time spent":"406.40768ms","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T22:21:42.822125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794459064776984,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T22:21:43.323243Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794459064776984,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T22:21:43.395227Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.131373405s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T22:21:43.395613Z","caller":"traceutil/trace.go:171","msg":"trace[72413126] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"1.131820269s","start":"2026-03-31T22:21:42.263771Z","end":"2026-03-31T22:21:43.395591Z","steps":["trace[72413126] 'process raft request' (duration: 1.131663685s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:43.395726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:42.263759Z","time spent":"1.131898152s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:43.580042Z","caller":"traceutil/trace.go:171","msg":"trace[1753324781] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"1.314574188s","start":"2026-03-31T22:21:42.265451Z","end":"2026-03-31T22:21:43.580026Z","steps":["trace[1753324781] 'process raft request' (duration: 1.314515397s)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:43.580076Z","caller":"traceutil/trace.go:171","msg":"trace[1316790957] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"1.315292672s","start":"2026-03-31T22:21:42.264749Z","end":"2026-03-31T22:21:43.580041Z","steps":["trace[1316790957] 'process raft request' (duration: 1.315107966s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:43.580143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:42.265425Z","time spent":"1.314662881s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:43.58013Z","caller":"traceutil/trace.go:171","msg":"trace[166187097] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"1.314696373s","start":"2026-03-31T22:21:42.265408Z","end":"2026-03-31T22:21:43.580104Z","steps":["trace[166187097] 'process raft request' (duration: 1.314536128s)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:43.580248Z","caller":"traceutil/trace.go:171","msg":"trace[277426315] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"1.314397483s","start":"2026-03-31T22:21:42.265846Z","end":"2026-03-31T22:21:43.580243Z","steps":["trace[277426315] 'process raft request' (duration: 1.314162326s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:43.580253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:42.264741Z","time spent":"1.315409575s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:21:43.580279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:42.265835Z","time spent":"1.314427204s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:43.580507Z","caller":"traceutil/trace.go:171","msg":"trace[2013354303] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"670.98619ms","start":"2026-03-31T22:21:42.909514Z","end":"2026-03-31T22:21:43.5805Z","steps":["trace[2013354303] 'process raft request' (duration: 670.706761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:43.580561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:42.909492Z","time spent":"671.03429ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:21:43.58061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:42.265383Z","time spent":"1.315018302s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:43.580676Z","caller":"traceutil/trace.go:171","msg":"trace[1931261026] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"653.287157ms","start":"2026-03-31T22:21:42.927383Z","end":"2026-03-31T22:21:43.580671Z","steps":["trace[1931261026] 'process raft request' (duration: 653.05164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:43.580736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:42.927366Z","time spent":"653.353779ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:21:43.58052Z","caller":"traceutil/trace.go:171","msg":"trace[834242504] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1111; }","duration":"1.259151377s","start":"2026-03-31T22:21:42.321349Z","end":"2026-03-31T22:21:43.580501Z","steps":["trace[834242504] 'read index received' (duration: 1.074042262s)","trace[834242504] 'applied index is now lower than readState.Index' (duration: 185.108085ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:21:43.580872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.286924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:21:43.580892Z","caller":"traceutil/trace.go:171","msg":"trace[447318352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"730.333406ms","start":"2026-03-31T22:21:42.850553Z","end":"2026-03-31T22:21:43.580887Z","steps":["trace[447318352] 'agreement among raft nodes before linearized reading' (duration: 730.284534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:43.580905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:42.850537Z","time spent":"730.365187ms","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T22:21:43.580873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259480487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:21:43.581045Z","caller":"traceutil/trace.go:171","msg":"trace[1097034135] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1076; }","duration":"1.259729155s","start":"2026-03-31T22:21:42.321301Z","end":"2026-03-31T22:21:43.58103Z","steps":["trace[1097034135] 'agreement among raft nodes before linearized reading' (duration: 1.259406185s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:43.581143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:42.321268Z","time spent":"1.259856779s","remote":"127.0.0.1:56124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:21:43.581545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.745318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:21:43.581927Z","caller":"traceutil/trace.go:171","msg":"trace[147235402] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1076; }","duration":"148.846912ms","start":"2026-03-31T22:21:43.432743Z","end":"2026-03-31T22:21:43.58159Z","steps":["trace[147235402] 'agreement among raft nodes before linearized reading' (duration: 148.282184ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:47.765856Z","caller":"traceutil/trace.go:171","msg":"trace[653696990] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"158.370494ms","start":"2026-03-31T22:21:47.607448Z","end":"2026-03-31T22:21:47.765818Z","steps":["trace[653696990] 'process raft request' (duration: 109.452589ms)","trace[653696990] 'compare' (duration: 48.782821ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:21:47.774438Z","caller":"traceutil/trace.go:171","msg":"trace[642783923] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"165.563792ms","start":"2026-03-31T22:21:47.608859Z","end":"2026-03-31T22:21:47.774423Z","steps":["trace[642783923] 'process raft request' (duration: 165.352356ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:47.774537Z","caller":"traceutil/trace.go:171","msg":"trace[1346711319] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"164.100687ms","start":"2026-03-31T22:21:47.610424Z","end":"2026-03-31T22:21:47.774524Z","steps":["trace[1346711319] 'process raft request' (duration: 163.975593ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:49.61228Z","caller":"traceutil/trace.go:171","msg":"trace[370780375] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"177.383349ms","start":"2026-03-31T22:21:49.434868Z","end":"2026-03-31T22:21:49.612252Z","steps":["trace[370780375] 'process raft request' (duration: 177.246515ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:49.708975Z","caller":"traceutil/trace.go:171","msg":"trace[2105135790] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1133; }","duration":"101.806106ms","start":"2026-03-31T22:21:49.607142Z","end":"2026-03-31T22:21:49.708949Z","steps":["trace[2105135790] 'read index received' (duration: 5.057561ms)","trace[2105135790] 'applied index is now lower than readState.Index' (duration: 96.747565ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:21:49.709018Z","caller":"traceutil/trace.go:171","msg":"trace[201412270] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"106.537646ms","start":"2026-03-31T22:21:49.602453Z","end":"2026-03-31T22:21:49.708991Z","steps":["trace[201412270] 'process raft request' (duration: 85.137796ms)","trace[201412270] 'compare' (duration: 21.247576ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:21:49.709272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.044894ms","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-03-31T22:21:49.709369Z","caller":"traceutil/trace.go:171","msg":"trace[1591616990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1092; }","duration":"102.241661ms","start":"2026-03-31T22:21:49.607101Z","end":"2026-03-31T22:21:49.709342Z","steps":["trace[1591616990] 'agreement among raft nodes before linearized reading' (duration: 101.930551ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:50.065656Z","caller":"traceutil/trace.go:171","msg":"trace[1089956952] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1135; }","duration":"295.159933ms","start":"2026-03-31T22:21:49.770465Z","end":"2026-03-31T22:21:50.065625Z","steps":["trace[1089956952] 'read index received' (duration: 294.907985ms)","trace[1089956952] 'applied index is now lower than readState.Index' (duration: 250.878µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:21:50.065779Z","caller":"traceutil/trace.go:171","msg":"trace[1152495785] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"351.429402ms","start":"2026-03-31T22:21:49.714338Z","end":"2026-03-31T22:21:50.065767Z","steps":["trace[1152495785] 'process raft request' (duration: 351.132943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:50.06588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:49.714302Z","time spent":"351.514445ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:21:50.066049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.083877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:21:50.066163Z","caller":"traceutil/trace.go:171","msg":"trace[1592211830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"215.199031ms","start":"2026-03-31T22:21:49.850906Z","end":"2026-03-31T22:21:50.066105Z","steps":["trace[1592211830] 'agreement among raft nodes before linearized reading' (duration: 215.024405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:50.066151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.659569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-31T22:21:50.066171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.294105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T22:21:50.06624Z","caller":"traceutil/trace.go:171","msg":"trace[1576778350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1093; }","duration":"295.946649ms","start":"2026-03-31T22:21:49.770269Z","end":"2026-03-31T22:21:50.066216Z","steps":["trace[1576778350] 'agreement among raft nodes before linearized reading' (duration: 295.701401ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:50.066418Z","caller":"traceutil/trace.go:171","msg":"trace[9069836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1093; }","duration":"288.513382ms","start":"2026-03-31T22:21:49.777769Z","end":"2026-03-31T22:21:50.066283Z","steps":["trace[9069836] 'agreement among raft nodes before linearized reading' (duration: 288.072178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:50.066414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.694826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T22:21:50.066529Z","caller":"traceutil/trace.go:171","msg":"trace[1261965634] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1093; }","duration":"287.83887ms","start":"2026-03-31T22:21:49.778677Z","end":"2026-03-31T22:21:50.066516Z","steps":["trace[1261965634] 'agreement among raft nodes before linearized reading' (duration: 287.355305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:00.091799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.169303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T22:22:00.091859Z","caller":"traceutil/trace.go:171","msg":"trace[1380381801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1174; }","duration":"444.257205ms","start":"2026-03-31T22:21:59.647588Z","end":"2026-03-31T22:22:00.091845Z","steps":["trace[1380381801] 'range keys from in-memory index tree' (duration: 444.042949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:00.091888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:59.647575Z","time spent":"444.307088ms","remote":"127.0.0.1:56278","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-03-31T22:22:00.092153Z","caller":"traceutil/trace.go:171","msg":"trace[1299014641] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1221; }","duration":"243.807818ms","start":"2026-03-31T22:21:59.84832Z","end":"2026-03-31T22:22:00.092127Z","steps":["trace[1299014641] 'read index received' (duration: 230.938819ms)","trace[1299014641] 'applied index is now lower than readState.Index' (duration: 12.867639ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:22:00.092184Z","caller":"traceutil/trace.go:171","msg":"trace[471967973] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"353.126016ms","start":"2026-03-31T22:21:59.739048Z","end":"2026-03-31T22:22:00.092174Z","steps":["trace[471967973] 'process raft request' (duration: 340.237176ms)","trace[471967973] 'compare' (duration: 11.93408ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:22:00.092227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.908121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:22:00.092246Z","caller":"traceutil/trace.go:171","msg":"trace[1393229003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1175; }","duration":"243.950312ms","start":"2026-03-31T22:21:59.84829Z","end":"2026-03-31T22:22:00.092241Z","steps":["trace[1393229003] 'agreement among raft nodes before linearized reading' (duration: 243.918001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:00.092307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:59.739028Z","time spent":"353.200319ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:22:06.210821Z","caller":"traceutil/trace.go:171","msg":"trace[1516951755] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"101.563152ms","start":"2026-03-31T22:22:06.109238Z","end":"2026-03-31T22:22:06.210801Z","steps":["trace[1516951755] 'process raft request' (duration: 101.352565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:06.516529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.244505ms","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-03-31T22:22:06.51697Z","caller":"traceutil/trace.go:171","msg":"trace[128966092] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1200; }","duration":"147.752821ms","start":"2026-03-31T22:22:06.369195Z","end":"2026-03-31T22:22:06.516948Z","steps":["trace[128966092] 'count revisions from in-memory index tree' (duration: 147.106711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:12.59377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.946183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T22:22:12.59391Z","caller":"traceutil/trace.go:171","msg":"trace[2134537904] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"281.344242ms","start":"2026-03-31T22:22:12.312546Z","end":"2026-03-31T22:22:12.59389Z","steps":["trace[2134537904] 'process raft request' (duration: 42.188902ms)","trace[2134537904] 'compare' (duration: 238.801769ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:22:12.595933Z","caller":"traceutil/trace.go:171","msg":"trace[679050787] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1264; }","duration":"275.6015ms","start":"2026-03-31T22:22:12.320317Z","end":"2026-03-31T22:22:12.595919Z","steps":["trace[679050787] 'read index received' (duration: 34.428005ms)","trace[679050787] 'applied index is now lower than readState.Index' (duration: 241.172665ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:22:12.596224Z","caller":"traceutil/trace.go:171","msg":"trace[902571389] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"282.786658ms","start":"2026-03-31T22:22:12.313424Z","end":"2026-03-31T22:22:12.596211Z","steps":["trace[902571389] 'process raft request' (duration: 282.394245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:12.596482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.020523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T22:22:12.596542Z","caller":"traceutil/trace.go:171","msg":"trace[661362758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"276.100795ms","start":"2026-03-31T22:22:12.320428Z","end":"2026-03-31T22:22:12.596529Z","steps":["trace[661362758] 'agreement among raft nodes before linearized reading' (duration: 275.9284ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:12.59656Z","caller":"traceutil/trace.go:171","msg":"trace[1255608914] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"282.764198ms","start":"2026-03-31T22:22:12.313782Z","end":"2026-03-31T22:22:12.596546Z","steps":["trace[1255608914] 'process raft request' (duration: 282.102777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:12.596733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.414055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T22:22:12.596762Z","caller":"traceutil/trace.go:171","msg":"trace[1867004537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1217; }","duration":"276.458007ms","start":"2026-03-31T22:22:12.320296Z","end":"2026-03-31T22:22:12.596754Z","steps":["trace[1867004537] 'agreement among raft nodes before linearized reading' (duration: 276.390485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:15.098545Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794459064777342,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T22:22:15.598697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794459064777342,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T22:22:15.645392Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.176781434s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T22:22:15.645766Z","caller":"traceutil/trace.go:171","msg":"trace[5797925] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"1.04780606s","start":"2026-03-31T22:22:14.597934Z","end":"2026-03-31T22:22:15.64574Z","steps":["trace[5797925] 'read index received' (duration: 1.047644245s)","trace[5797925] 'applied index is now lower than readState.Index' (duration: 160.895µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:22:15.645839Z","caller":"traceutil/trace.go:171","msg":"trace[991750766] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"1.17728832s","start":"2026-03-31T22:22:14.468515Z","end":"2026-03-31T22:22:15.645803Z","steps":["trace[991750766] 'process raft request' (duration: 1.177070803s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:15.645998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046732295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-31T22:22:15.646024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.903714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.73\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-31T22:22:15.646043Z","caller":"traceutil/trace.go:171","msg":"trace[1986583274] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1229; }","duration":"1.046854339s","start":"2026-03-31T22:22:14.599175Z","end":"2026-03-31T22:22:15.646029Z","steps":["trace[1986583274] 'agreement among raft nodes before linearized reading' (duration: 1.046698874s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:15.646057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040708433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-31T22:22:15.646098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:14.46849Z","time spent":"1.177474076s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:22:15.646175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040889839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-31T22:22:15.646173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046493837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T22:22:15.646208Z","caller":"traceutil/trace.go:171","msg":"trace[324395117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1229; }","duration":"1.04093854s","start":"2026-03-31T22:22:14.605262Z","end":"2026-03-31T22:22:15.6462Z","steps":["trace[324395117] 'agreement among raft nodes before linearized reading' (duration: 1.040843157s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:15.64622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.838922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T22:22:15.646223Z","caller":"traceutil/trace.go:171","msg":"trace[1845683335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1229; }","duration":"1.04656099s","start":"2026-03-31T22:22:14.599646Z","end":"2026-03-31T22:22:15.646207Z","steps":["trace[1845683335] 'agreement among raft nodes before linearized reading' (duration: 1.046462377s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:15.646239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:14.605249Z","time spent":"1.040983332s","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T22:22:15.646264Z","caller":"traceutil/trace.go:171","msg":"trace[1447970007] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1229; }","duration":"921.913683ms","start":"2026-03-31T22:22:14.724338Z","end":"2026-03-31T22:22:15.646252Z","steps":["trace[1447970007] 'agreement among raft nodes before linearized reading' (duration: 921.82783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:15.646291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:14.599631Z","time spent":"1.046647343s","remote":"127.0.0.1:56278","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-03-31T22:22:15.646309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:14.724315Z","time spent":"921.980895ms","remote":"127.0.0.1:56342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-03-31T22:22:15.646132Z","caller":"traceutil/trace.go:171","msg":"trace[431058925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1229; }","duration":"1.040804007s","start":"2026-03-31T22:22:14.605311Z","end":"2026-03-31T22:22:15.646115Z","steps":["trace[431058925] 'agreement among raft nodes before linearized reading' (duration: 1.04059724s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:15.646085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:14.599154Z","time spent":"1.046922191s","remote":"127.0.0.1:56278","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-03-31T22:22:15.646559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:14.6053Z","time spent":"1.041190209s","remote":"127.0.0.1:56278","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-03-31T22:22:15.646077Z","caller":"traceutil/trace.go:171","msg":"trace[748443797] range","detail":"{range_begin:/registry/masterleases/199.19.213.73; range_end:; response_count:1; response_revision:1229; }","duration":"652.996547ms","start":"2026-03-31T22:22:14.993065Z","end":"2026-03-31T22:22:15.646062Z","steps":["trace[748443797] 'agreement among raft nodes before linearized reading' (duration: 652.834212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:15.646685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:14.993044Z","time spent":"653.631047ms","remote":"127.0.0.1:55992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.73\" "} {"level":"warn","ts":"2026-03-31T22:22:15.647297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048657707s","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-03-31T22:22:15.647378Z","caller":"traceutil/trace.go:171","msg":"trace[108987250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1229; }","duration":"1.049433971s","start":"2026-03-31T22:22:14.597927Z","end":"2026-03-31T22:22:15.647361Z","steps":["trace[108987250] 'agreement among raft nodes before linearized reading' (duration: 1.048566284s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:15.647426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:14.597868Z","time spent":"1.049544585s","remote":"127.0.0.1:56278","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-03-31T22:22:16.413112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.813737ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-03-31T22:22:16.413211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:15.650603Z","time spent":"762.603255ms","remote":"127.0.0.1:55992","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-31T22:22:16.599959Z","caller":"traceutil/trace.go:171","msg":"trace[762899387] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"946.319342ms","start":"2026-03-31T22:22:15.653617Z","end":"2026-03-31T22:22:16.599937Z","steps":["trace[762899387] 'process raft request' (duration: 946.240709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:16.600126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:15.653603Z","time spent":"946.451295ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:22:16.600278Z","caller":"traceutil/trace.go:171","msg":"trace[129162273] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"946.620261ms","start":"2026-03-31T22:22:15.653639Z","end":"2026-03-31T22:22:16.60026Z","steps":["trace[129162273] 'process raft request' (duration: 946.26133ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:16.600381Z","caller":"traceutil/trace.go:171","msg":"trace[283244170] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"946.829667ms","start":"2026-03-31T22:22:15.653538Z","end":"2026-03-31T22:22:16.600368Z","steps":["trace[283244170] 'process raft request' (duration: 916.283796ms)","trace[283244170] 'compare' (duration: 29.908991ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:22:16.6004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:15.65363Z","time spent":"946.699343ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:22:16.600443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:15.653507Z","time spent":"946.90615ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:22:16.600613Z","caller":"traceutil/trace.go:171","msg":"trace[823213705] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"945.997601ms","start":"2026-03-31T22:22:15.654608Z","end":"2026-03-31T22:22:16.600605Z","steps":["trace[823213705] 'process raft request' (duration: 945.580998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:16.600662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:15.654598Z","time spent":"946.042012ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:22:16.600959Z","caller":"traceutil/trace.go:171","msg":"trace[1523609741] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1279; }","duration":"749.795288ms","start":"2026-03-31T22:22:15.851155Z","end":"2026-03-31T22:22:16.600951Z","steps":["trace[1523609741] 'read index received' (duration: 313.093012ms)","trace[1523609741] 'applied index is now lower than readState.Index' (duration: 436.701556ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:22:16.601189Z","caller":"traceutil/trace.go:171","msg":"trace[1162952819] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"946.261599ms","start":"2026-03-31T22:22:15.654916Z","end":"2026-03-31T22:22:16.601177Z","steps":["trace[1162952819] 'process raft request' (duration: 945.66224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:16.601254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:15.6549Z","time spent":"946.322612ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:22:16.60103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.87134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:22:16.606103Z","caller":"traceutil/trace.go:171","msg":"trace[34809267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"754.972903ms","start":"2026-03-31T22:22:15.85112Z","end":"2026-03-31T22:22:16.606093Z","steps":["trace[34809267] 'agreement among raft nodes before linearized reading' (duration: 749.86318ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:16.606135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:15.851102Z","time spent":"755.023354ms","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T22:22:16.655595Z","caller":"traceutil/trace.go:171","msg":"trace[1156632815] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"241.419011ms","start":"2026-03-31T22:22:16.414161Z","end":"2026-03-31T22:22:16.65558Z","steps":["trace[1156632815] 'process raft request' (duration: 241.174194ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:16.655835Z","caller":"traceutil/trace.go:171","msg":"trace[200128173] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"309.397124ms","start":"2026-03-31T22:22:16.346406Z","end":"2026-03-31T22:22:16.655803Z","steps":["trace[200128173] 'process raft request' (duration: 308.812406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:16.655947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:16.346381Z","time spent":"309.481927ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:22:16.756747Z","caller":"traceutil/trace.go:171","msg":"trace[924022115] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"102.598865ms","start":"2026-03-31T22:22:16.654121Z","end":"2026-03-31T22:22:16.75672Z","steps":["trace[924022115] 'process raft request' (duration: 67.604221ms)","trace[924022115] 'compare' (duration: 34.86324ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:22:17.600109Z","caller":"traceutil/trace.go:171","msg":"trace[1703515622] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"203.780304ms","start":"2026-03-31T22:22:17.396312Z","end":"2026-03-31T22:22:17.600092Z","steps":["trace[1703515622] 'process raft request' (duration: 203.674711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:18.121269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.224614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:22:18.121348Z","caller":"traceutil/trace.go:171","msg":"trace[1629922321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1238; }","duration":"273.314036ms","start":"2026-03-31T22:22:17.848013Z","end":"2026-03-31T22:22:18.121327Z","steps":["trace[1629922321] 'range keys from in-memory index tree' (duration: 273.12415ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:18.667501Z","caller":"traceutil/trace.go:171","msg":"trace[1994469361] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"241.458973ms","start":"2026-03-31T22:22:18.426017Z","end":"2026-03-31T22:22:18.667476Z","steps":["trace[1994469361] 'read index received' (duration: 241.302148ms)","trace[1994469361] 'applied index is now lower than readState.Index' (duration: 155.465µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:22:18.667524Z","caller":"traceutil/trace.go:171","msg":"trace[436003087] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"314.616172ms","start":"2026-03-31T22:22:18.352879Z","end":"2026-03-31T22:22:18.667495Z","steps":["trace[436003087] 'process raft request' (duration: 314.437716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:18.667671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:18.352852Z","time spent":"314.753085ms","remote":"127.0.0.1:56056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:22:18.667846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.678111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:22:18.668078Z","caller":"traceutil/trace.go:171","msg":"trace[731068609] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1239; }","duration":"241.926839ms","start":"2026-03-31T22:22:18.425978Z","end":"2026-03-31T22:22:18.667905Z","steps":["trace[731068609] 'agreement among raft nodes before linearized reading' (duration: 241.605408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:18.683479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.512512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:22:18.683544Z","caller":"traceutil/trace.go:171","msg":"trace[923779650] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1240; }","duration":"130.612015ms","start":"2026-03-31T22:22:18.552916Z","end":"2026-03-31T22:22:18.683528Z","steps":["trace[923779650] 'agreement among raft nodes before linearized reading' (duration: 130.494892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:18.839046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.027831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T22:22:18.839135Z","caller":"traceutil/trace.go:171","msg":"trace[540123085] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"167.206341ms","start":"2026-03-31T22:22:18.671913Z","end":"2026-03-31T22:22:18.83912Z","steps":["trace[540123085] 'process raft request' (duration: 62.052715ms)","trace[540123085] 'compare' (duration: 104.917728ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:22:18.883345Z","caller":"traceutil/trace.go:171","msg":"trace[1475138213] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"195.012936ms","start":"2026-03-31T22:22:18.688304Z","end":"2026-03-31T22:22:18.883317Z","steps":["trace[1475138213] 'process raft request' (duration: 194.812859ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:18.88342Z","caller":"traceutil/trace.go:171","msg":"trace[500674711] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"195.034706ms","start":"2026-03-31T22:22:18.68836Z","end":"2026-03-31T22:22:18.883394Z","steps":["trace[500674711] 'process raft request' (duration: 194.875351ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:18.883658Z","caller":"traceutil/trace.go:171","msg":"trace[111492022] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"193.748275ms","start":"2026-03-31T22:22:18.689893Z","end":"2026-03-31T22:22:18.883641Z","steps":["trace[111492022] 'process raft request' (duration: 193.443105ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:18.883743Z","caller":"traceutil/trace.go:171","msg":"trace[1273525140] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"193.843038ms","start":"2026-03-31T22:22:18.689884Z","end":"2026-03-31T22:22:18.883727Z","steps":["trace[1273525140] 'process raft request' (duration: 193.384033ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:18.883797Z","caller":"traceutil/trace.go:171","msg":"trace[991116365] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"193.436035ms","start":"2026-03-31T22:22:18.690355Z","end":"2026-03-31T22:22:18.883791Z","steps":["trace[991116365] 'process raft request' (duration: 193.246769ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:19.194416Z","caller":"traceutil/trace.go:171","msg":"trace[266857574] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1292; }","duration":"350.738429ms","start":"2026-03-31T22:22:18.843655Z","end":"2026-03-31T22:22:19.194393Z","steps":["trace[266857574] 'read index received' (duration: 39.386953ms)","trace[266857574] 'applied index is now lower than readState.Index' (duration: 311.350456ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:22:19.194449Z","caller":"traceutil/trace.go:171","msg":"trace[1859546771] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"409.554501ms","start":"2026-03-31T22:22:18.784865Z","end":"2026-03-31T22:22:19.19442Z","steps":["trace[1859546771] 'process raft request' (duration: 409.352024ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:19.19459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:18.784839Z","time spent":"409.673835ms","remote":"127.0.0.1:56056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:22:19.194449Z","caller":"traceutil/trace.go:171","msg":"trace[1636854177] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"350.76213ms","start":"2026-03-31T22:22:18.843665Z","end":"2026-03-31T22:22:19.194427Z","steps":["trace[1636854177] 'process raft request' (duration: 350.687328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:19.194682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:18.843637Z","time spent":"351.004518ms","remote":"127.0.0.1:56056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":767,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:22:19.194693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.016317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:22:19.194709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.014908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-03-31T22:22:19.194771Z","caller":"traceutil/trace.go:171","msg":"trace[1254864634] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1248; }","duration":"351.196833ms","start":"2026-03-31T22:22:18.843563Z","end":"2026-03-31T22:22:19.194759Z","steps":["trace[1254864634] 'agreement among raft nodes before linearized reading' (duration: 350.959096ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:19.194773Z","caller":"traceutil/trace.go:171","msg":"trace[1562895585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"345.185172ms","start":"2026-03-31T22:22:18.849567Z","end":"2026-03-31T22:22:19.194752Z","steps":["trace[1562895585] 'agreement among raft nodes before linearized reading' (duration: 344.997406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:19.19481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:18.843508Z","time spent":"351.292427ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-31T22:22:19.194818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:18.849546Z","time spent":"345.263065ms","remote":"127.0.0.1:55962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T22:22:21.216422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.444052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T22:22:21.216627Z","caller":"traceutil/trace.go:171","msg":"trace[369773116] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"316.229122ms","start":"2026-03-31T22:22:20.900384Z","end":"2026-03-31T22:22:21.216613Z","steps":["trace[369773116] 'process raft request' (duration: 316.16907ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:21.216688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:20.900373Z","time spent":"316.290344ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:22:21.216667Z","caller":"traceutil/trace.go:171","msg":"trace[229547412] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"316.46649ms","start":"2026-03-31T22:22:20.90017Z","end":"2026-03-31T22:22:21.216637Z","steps":["trace[229547412] 'process raft request' (duration: 179.74204ms)","trace[229547412] 'compare' (duration: 136.342848ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:22:21.216801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:20.900156Z","time spent":"316.573893ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:22:21.216813Z","caller":"traceutil/trace.go:171","msg":"trace[1264078130] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"315.5443ms","start":"2026-03-31T22:22:20.90125Z","end":"2026-03-31T22:22:21.216794Z","steps":["trace[1264078130] 'process raft request' (duration: 315.334213ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:21.21689Z","caller":"traceutil/trace.go:171","msg":"trace[1149108121] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"316.632065ms","start":"2026-03-31T22:22:20.900249Z","end":"2026-03-31T22:22:21.216881Z","steps":["trace[1149108121] 'process raft request' (duration: 316.257793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:21.216892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:20.901219Z","time spent":"315.631183ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:22:21.21694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:20.900239Z","time spent":"316.677207ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:22:22.515074Z","caller":"traceutil/trace.go:171","msg":"trace[1419700666] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"217.516741ms","start":"2026-03-31T22:22:22.297541Z","end":"2026-03-31T22:22:22.515058Z","steps":["trace[1419700666] 'process raft request' (duration: 217.410987ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:25.3814Z","caller":"traceutil/trace.go:171","msg":"trace[340121169] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"150.548581ms","start":"2026-03-31T22:22:25.230825Z","end":"2026-03-31T22:22:25.381374Z","steps":["trace[340121169] 'process raft request' (duration: 150.375035ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:25.390083Z","caller":"traceutil/trace.go:171","msg":"trace[661486013] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"156.363206ms","start":"2026-03-31T22:22:25.23371Z","end":"2026-03-31T22:22:25.390073Z","steps":["trace[661486013] 'process raft request' (duration: 156.335645ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:25.39019Z","caller":"traceutil/trace.go:171","msg":"trace[463702126] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"158.798323ms","start":"2026-03-31T22:22:25.231358Z","end":"2026-03-31T22:22:25.390157Z","steps":["trace[463702126] 'process raft request' (duration: 158.614237ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:25.390375Z","caller":"traceutil/trace.go:171","msg":"trace[1484947209] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"157.630526ms","start":"2026-03-31T22:22:25.232603Z","end":"2026-03-31T22:22:25.390234Z","steps":["trace[1484947209] 'process raft request' (duration: 157.418759ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:30.438544Z","caller":"traceutil/trace.go:171","msg":"trace[64883700] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1386; }","duration":"171.424004ms","start":"2026-03-31T22:22:30.267098Z","end":"2026-03-31T22:22:30.438522Z","steps":["trace[64883700] 'read index received' (duration: 171.211488ms)","trace[64883700] 'applied index is now lower than readState.Index' (duration: 211.446µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:22:30.438672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.575999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T22:22:30.438653Z","caller":"traceutil/trace.go:171","msg":"trace[1364479812] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"189.345405ms","start":"2026-03-31T22:22:30.249276Z","end":"2026-03-31T22:22:30.438621Z","steps":["trace[1364479812] 'process raft request' (duration: 189.098637ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:22:30.438704Z","caller":"traceutil/trace.go:171","msg":"trace[1216541833] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1334; }","duration":"171.657852ms","start":"2026-03-31T22:22:30.267037Z","end":"2026-03-31T22:22:30.438694Z","steps":["trace[1216541833] 'agreement among raft nodes before linearized reading' (duration: 171.58272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:31.338479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794459064777536,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T22:22:31.725732Z","caller":"traceutil/trace.go:171","msg":"trace[950249987] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"887.678256ms","start":"2026-03-31T22:22:30.838028Z","end":"2026-03-31T22:22:31.725706Z","steps":["trace[950249987] 'read index received' (duration: 885.92959ms)","trace[950249987] 'applied index is now lower than readState.Index' (duration: 1.747096ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:22:31.725788Z","caller":"traceutil/trace.go:171","msg":"trace[1914356907] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"935.484788ms","start":"2026-03-31T22:22:30.790273Z","end":"2026-03-31T22:22:31.725758Z","steps":["trace[1914356907] 'process raft request' (duration: 933.489804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:31.725882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.836341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T22:22:31.725928Z","caller":"traceutil/trace.go:171","msg":"trace[791073099] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1335; }","duration":"887.938434ms","start":"2026-03-31T22:22:30.837977Z","end":"2026-03-31T22:22:31.725915Z","steps":["trace[791073099] 'agreement among raft nodes before linearized reading' (duration: 887.845351ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:31.725977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:30.790254Z","time spent":"935.631112ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:22:31.726029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:30.837952Z","time spent":"888.004806ms","remote":"127.0.0.1:47110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-03-31T22:22:32.084828Z","caller":"traceutil/trace.go:171","msg":"trace[1176962951] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"359.010653ms","start":"2026-03-31T22:22:31.725797Z","end":"2026-03-31T22:22:32.084807Z","steps":["trace[1176962951] 'read index received' (duration: 321.83536ms)","trace[1176962951] 'applied index is now lower than readState.Index' (duration: 37.173183ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:22:32.084927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234525132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:22:32.084949Z","caller":"traceutil/trace.go:171","msg":"trace[373312628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"1.234616534s","start":"2026-03-31T22:22:30.850325Z","end":"2026-03-31T22:22:32.084942Z","steps":["trace[373312628] 'agreement among raft nodes before linearized reading' (duration: 1.234552352s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:32.084973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:30.850303Z","time spent":"1.234663916s","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T22:22:32.084931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.71815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:22:32.085025Z","caller":"traceutil/trace.go:171","msg":"trace[50867938] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1335; }","duration":"438.872804ms","start":"2026-03-31T22:22:31.646134Z","end":"2026-03-31T22:22:32.085007Z","steps":["trace[50867938] 'agreement among raft nodes before linearized reading' (duration: 438.710359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:32.085036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.691343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T22:22:32.085075Z","caller":"traceutil/trace.go:171","msg":"trace[1404630594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1335; }","duration":"671.763745ms","start":"2026-03-31T22:22:31.413302Z","end":"2026-03-31T22:22:32.085066Z","steps":["trace[1404630594] 'agreement among raft nodes before linearized reading' (duration: 671.628891ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:32.085079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:31.646111Z","time spent":"438.956887ms","remote":"127.0.0.1:47100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:22:32.085104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:31.413282Z","time spent":"671.816617ms","remote":"127.0.0.1:56278","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-03-31T22:22:32.085085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.376835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:22:32.085182Z","caller":"traceutil/trace.go:171","msg":"trace[1036854615] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1335; }","duration":"831.530319ms","start":"2026-03-31T22:22:31.253634Z","end":"2026-03-31T22:22:32.085164Z","steps":["trace[1036854615] 'agreement among raft nodes before linearized reading' (duration: 831.354744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:32.085241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:31.253614Z","time spent":"831.614202ms","remote":"127.0.0.1:47132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:22:32.085275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.827653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-03-31T22:22:32.085305Z","caller":"traceutil/trace.go:171","msg":"trace[786827639] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1335; }","duration":"987.886605ms","start":"2026-03-31T22:22:31.097409Z","end":"2026-03-31T22:22:32.085296Z","steps":["trace[786827639] 'agreement among raft nodes before linearized reading' (duration: 987.803002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:32.085346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:31.097389Z","time spent":"987.950486ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":546,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-31T22:22:32.085345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.035087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T22:22:32.085367Z","caller":"traceutil/trace.go:171","msg":"trace[1656003787] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1335; }","duration":"778.10714ms","start":"2026-03-31T22:22:31.307253Z","end":"2026-03-31T22:22:32.085361Z","steps":["trace[1656003787] 'agreement among raft nodes before linearized reading' (duration: 778.067848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:32.085385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:31.307234Z","time spent":"778.14583ms","remote":"127.0.0.1:56284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:22:32.085198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.917655ms","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":"info","ts":"2026-03-31T22:22:32.085572Z","caller":"traceutil/trace.go:171","msg":"trace[307020850] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1335; }","duration":"955.333249ms","start":"2026-03-31T22:22:31.130215Z","end":"2026-03-31T22:22:32.085548Z","steps":["trace[307020850] 'agreement among raft nodes before linearized reading' (duration: 954.957067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:32.085601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09995347s","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-03-31T22:22:32.085628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:31.130194Z","time spent":"955.415422ms","remote":"127.0.0.1:56228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-03-31T22:22:32.085638Z","caller":"traceutil/trace.go:171","msg":"trace[1561817355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1335; }","duration":"1.099991031s","start":"2026-03-31T22:22:30.985636Z","end":"2026-03-31T22:22:32.085627Z","steps":["trace[1561817355] 'agreement among raft nodes before linearized reading' (duration: 1.099842886s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:32.085671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:30.98558Z","time spent":"1.100085274s","remote":"127.0.0.1:56278","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-03-31T22:22:32.085517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.455458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T22:22:32.085724Z","caller":"traceutil/trace.go:171","msg":"trace[1885860745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1335; }","duration":"683.692525ms","start":"2026-03-31T22:22:31.402017Z","end":"2026-03-31T22:22:32.085709Z","steps":["trace[1885860745] 'agreement among raft nodes before linearized reading' (duration: 683.458837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:32.085975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:31.401988Z","time spent":"683.774237ms","remote":"127.0.0.1:56278","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-03-31T22:22:32.086136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.730516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T22:22:32.08617Z","caller":"traceutil/trace.go:171","msg":"trace[1518506430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1335; }","duration":"672.778378ms","start":"2026-03-31T22:22:31.413384Z","end":"2026-03-31T22:22:32.086163Z","steps":["trace[1518506430] 'agreement among raft nodes before linearized reading' (duration: 671.475987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:32.086141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.217669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T22:22:32.086235Z","caller":"traceutil/trace.go:171","msg":"trace[1852688411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1335; }","duration":"677.341893ms","start":"2026-03-31T22:22:31.408878Z","end":"2026-03-31T22:22:32.08622Z","steps":["trace[1852688411] 'agreement among raft nodes before linearized reading' (duration: 677.109156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:22:32.086291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:31.408861Z","time spent":"677.415416ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T22:22:32.08619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:22:31.413381Z","time spent":"672.805159ms","remote":"127.0.0.1:56278","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-03-31T22:22:38.403732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.170583ms","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-03-31T22:22:38.403813Z","caller":"traceutil/trace.go:171","msg":"trace[1016817183] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1370; }","duration":"125.309838ms","start":"2026-03-31T22:22:38.278486Z","end":"2026-03-31T22:22:38.403796Z","steps":["trace[1016817183] 'count revisions from in-memory index tree' (duration: 125.049339ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:23:28.676263Z","caller":"traceutil/trace.go:171","msg":"trace[1124477961] linearizableReadLoop","detail":"{readStateIndex:1662; appliedIndex:1661; }","duration":"130.172599ms","start":"2026-03-31T22:23:28.54605Z","end":"2026-03-31T22:23:28.676222Z","steps":["trace[1124477961] 'read index received' (duration: 81.653973ms)","trace[1124477961] 'applied index is now lower than readState.Index' (duration: 48.516206ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:23:28.676502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.440267ms","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-03-31T22:23:28.676566Z","caller":"traceutil/trace.go:171","msg":"trace[249938386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1597; }","duration":"130.558471ms","start":"2026-03-31T22:23:28.545996Z","end":"2026-03-31T22:23:28.676555Z","steps":["trace[249938386] 'agreement among raft nodes before linearized reading' (duration: 130.387105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:23:28.676922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.862257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T22:23:28.677014Z","caller":"traceutil/trace.go:171","msg":"trace[1066349545] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1597; }","duration":"123.978932ms","start":"2026-03-31T22:23:28.553014Z","end":"2026-03-31T22:23:28.676993Z","steps":["trace[1066349545] 'agreement among raft nodes before linearized reading' (duration: 123.692563ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:23:28.676388Z","caller":"traceutil/trace.go:171","msg":"trace[660402036] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"131.682277ms","start":"2026-03-31T22:23:28.544672Z","end":"2026-03-31T22:23:28.676354Z","steps":["trace[660402036] 'process raft request' (duration: 83.117029ms)","trace[660402036] 'compare' (duration: 48.266189ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:23:28.677171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.997081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T22:23:28.677357Z","caller":"traceutil/trace.go:171","msg":"trace[1957237751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1597; }","duration":"124.170947ms","start":"2026-03-31T22:23:28.553142Z","end":"2026-03-31T22:23:28.677313Z","steps":["trace[1957237751] 'agreement among raft nodes before linearized reading' (duration: 123.709182ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:25:48.249012Z","caller":"traceutil/trace.go:171","msg":"trace[1029168993] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"632.001844ms","start":"2026-03-31T22:25:47.616987Z","end":"2026-03-31T22:25:48.248989Z","steps":["trace[1029168993] 'process raft request' (duration: 631.958332ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:25:48.24909Z","caller":"traceutil/trace.go:171","msg":"trace[1322775587] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"632.070166ms","start":"2026-03-31T22:25:47.616987Z","end":"2026-03-31T22:25:48.249057Z","steps":["trace[1322775587] 'process raft request' (duration: 631.830848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:25:48.249315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:25:47.616961Z","time spent":"632.122397ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:25:48.24933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:25:47.616959Z","time spent":"632.270991ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:25:48.258801Z","caller":"traceutil/trace.go:171","msg":"trace[344083645] linearizableReadLoop","detail":"{readStateIndex:2363; appliedIndex:2360; }","duration":"408.552496ms","start":"2026-03-31T22:25:47.850236Z","end":"2026-03-31T22:25:48.258789Z","steps":["trace[344083645] 'read index received' (duration: 398.587138ms)","trace[344083645] 'applied index is now lower than readState.Index' (duration: 9.964548ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:25:48.25888Z","caller":"traceutil/trace.go:171","msg":"trace[1539696865] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"639.930536ms","start":"2026-03-31T22:25:47.618905Z","end":"2026-03-31T22:25:48.258836Z","steps":["trace[1539696865] 'process raft request' (duration: 639.826273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:25:48.258943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.69091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:25:48.258956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.320109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T22:25:48.258948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.969104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:25:48.259Z","caller":"traceutil/trace.go:171","msg":"trace[1380369488] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2269; }","duration":"303.392701ms","start":"2026-03-31T22:25:47.955598Z","end":"2026-03-31T22:25:48.25899Z","steps":["trace[1380369488] 'agreement among raft nodes before linearized reading' (duration: 303.271437ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:25:48.258999Z","caller":"traceutil/trace.go:171","msg":"trace[651752652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2269; }","duration":"408.806294ms","start":"2026-03-31T22:25:47.850178Z","end":"2026-03-31T22:25:48.258984Z","steps":["trace[651752652] 'agreement among raft nodes before linearized reading' (duration: 408.67116ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:25:48.259013Z","caller":"traceutil/trace.go:171","msg":"trace[1558807034] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2269; }","duration":"152.077387ms","start":"2026-03-31T22:25:48.106923Z","end":"2026-03-31T22:25:48.259001Z","steps":["trace[1558807034] 'agreement among raft nodes before linearized reading' (duration: 151.977284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:25:48.259037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:25:47.955584Z","time spent":"303.443792ms","remote":"127.0.0.1:56090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:25:48.259042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:25:47.850159Z","time spent":"408.875395ms","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T22:25:48.259162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:25:47.618884Z","time spent":"640.102231ms","remote":"127.0.0.1:56278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:26:25.17071Z","caller":"traceutil/trace.go:171","msg":"trace[662648255] linearizableReadLoop","detail":"{readStateIndex:2548; appliedIndex:2547; }","duration":"112.77271ms","start":"2026-03-31T22:26:25.057914Z","end":"2026-03-31T22:26:25.170687Z","steps":["trace[662648255] 'read index received' (duration: 62.033575ms)","trace[662648255] 'applied index is now lower than readState.Index' (duration: 50.738135ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:26:25.170763Z","caller":"traceutil/trace.go:171","msg":"trace[1607460714] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"118.951117ms","start":"2026-03-31T22:26:25.051759Z","end":"2026-03-31T22:26:25.17071Z","steps":["trace[1607460714] 'process raft request' (duration: 68.289734ms)","trace[1607460714] 'compare' (duration: 50.485517ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:26:25.170956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.025018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-v5vg2\" ","response":"range_response_count:1 size:4907"} {"level":"info","ts":"2026-03-31T22:26:25.171042Z","caller":"traceutil/trace.go:171","msg":"trace[1369959921] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-v5vg2; range_end:; response_count:1; response_revision:2446; }","duration":"113.132821ms","start":"2026-03-31T22:26:25.057896Z","end":"2026-03-31T22:26:25.171029Z","steps":["trace[1369959921] 'agreement among raft nodes before linearized reading' (duration: 112.886473ms)"],"step_count":1}