{"level":"warn","ts":"2026-04-27T19:41:10.318253Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T19:41:10.318343Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.57: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.57:2380","--initial-cluster=instance=https://199.19.213.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.57: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-04-27T19:41:10.318418Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T19:41:10.318431Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.57:2380"]} {"level":"info","ts":"2026-04-27T19:41:10.318447Z","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-04-27T19:41:10.319117Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"]} {"level":"info","ts":"2026-04-27T19:41:10.319199Z","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.57:2380"],"listen-peer-urls":["https://199.19.213.57:2380"],"advertise-client-urls":["https://199.19.213.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.57: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-04-27T19:41:10.323632Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.220972ms"} {"level":"info","ts":"2026-04-27T19:41:10.337461Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ef5ef2a9544a39ef","cluster-id":"58d35553a3d286c0"} {"level":"info","ts":"2026-04-27T19:41:10.337549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T19:41:10.337567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became follower at term 0"} {"level":"info","ts":"2026-04-27T19:41:10.337576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ef5ef2a9544a39ef [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T19:41:10.337586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became follower at term 1"} {"level":"info","ts":"2026-04-27T19:41:10.337634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=(17248490431953123823)"} {"level":"warn","ts":"2026-04-27T19:41:10.34375Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T19:41:10.345247Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T19:41:10.346094Z","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-04-27T19:41:10.348445Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ef5ef2a9544a39ef","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T19:41:10.348548Z","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-04-27T19:41:10.348649Z","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-04-27T19:41:10.34866Z","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-04-27T19:41:10.349892Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ef5ef2a9544a39ef","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T19:41:10.350742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=(17248490431953123823)"} {"level":"info","ts":"2026-04-27T19:41:10.350883Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"58d35553a3d286c0","local-member-id":"ef5ef2a9544a39ef","added-peer-id":"ef5ef2a9544a39ef","added-peer-peer-urls":["https://199.19.213.57:2380"]} {"level":"info","ts":"2026-04-27T19:41:10.353373Z","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-04-27T19:41:10.353537Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.57:2380"} {"level":"info","ts":"2026-04-27T19:41:10.353578Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.57:2380"} {"level":"info","ts":"2026-04-27T19:41:10.353731Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ef5ef2a9544a39ef","initial-advertise-peer-urls":["https://199.19.213.57:2380"],"listen-peer-urls":["https://199.19.213.57:2380"],"advertise-client-urls":["https://199.19.213.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T19:41:10.353764Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T19:41:10.737995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T19:41:10.738051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T19:41:10.738135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef received MsgPreVoteResp from ef5ef2a9544a39ef at term 1"} {"level":"info","ts":"2026-04-27T19:41:10.73816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became candidate at term 2"} {"level":"info","ts":"2026-04-27T19:41:10.738201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef received MsgVoteResp from ef5ef2a9544a39ef at term 2"} {"level":"info","ts":"2026-04-27T19:41:10.738241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became leader at term 2"} {"level":"info","ts":"2026-04-27T19:41:10.738262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ef5ef2a9544a39ef elected leader ef5ef2a9544a39ef at term 2"} {"level":"info","ts":"2026-04-27T19:41:10.740195Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ef5ef2a9544a39ef","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.57:2379]}","request-path":"/0/members/ef5ef2a9544a39ef/attributes","cluster-id":"58d35553a3d286c0","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T19:41:10.742854Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T19:41:10.743272Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:41:10.746856Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T19:41:10.747065Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"58d35553a3d286c0","local-member-id":"ef5ef2a9544a39ef","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:41:10.747136Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:41:10.747157Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:41:10.747181Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T19:41:10.747207Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T19:41:10.747998Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.57:2379"} {"level":"info","ts":"2026-04-27T19:41:10.748572Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-27T19:41:20.136338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174728898249835447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:41:20.636942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174728898249835447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:41:21.137806Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174728898249835447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:41:21.248517Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.207220771s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:41:21.248833Z","caller":"traceutil/trace.go:171","msg":"trace[578007947] linearizableReadLoop","detail":"{readStateIndex:305; appliedIndex:304; }","duration":"1.613152689s","start":"2026-04-27T19:41:19.635664Z","end":"2026-04-27T19:41:21.248817Z","steps":["trace[578007947] 'read index received' (duration: 1.612988575s)","trace[578007947] 'applied index is now lower than readState.Index' (duration: 163.394µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:41:21.249018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613288573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:21.249121Z","caller":"traceutil/trace.go:171","msg":"trace[913126083] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:292; }","duration":"1.613486558s","start":"2026-04-27T19:41:19.635616Z","end":"2026-04-27T19:41:21.249102Z","steps":["trace[913126083] 'agreement among raft nodes before linearized reading' (duration: 1.613274033s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:21.249044Z","caller":"traceutil/trace.go:171","msg":"trace[2093605491] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"1.621843071s","start":"2026-04-27T19:41:19.627157Z","end":"2026-04-27T19:41:21.249Z","steps":["trace[2093605491] 'process raft request' (duration: 1.621542153s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:21.249198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:19.635596Z","time spent":"1.613577621s","remote":"127.0.0.1:38896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" "} {"level":"warn","ts":"2026-04-27T19:41:21.249171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.466084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-27T19:41:21.249286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.114883ms","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-04-27T19:41:21.249389Z","caller":"traceutil/trace.go:171","msg":"trace[1299038297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:292; }","duration":"824.710289ms","start":"2026-04-27T19:41:20.424652Z","end":"2026-04-27T19:41:21.249362Z","steps":["trace[1299038297] 'agreement among raft nodes before linearized reading' (duration: 824.408952ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:21.249411Z","caller":"traceutil/trace.go:171","msg":"trace[1238592164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:292; }","duration":"693.254057ms","start":"2026-04-27T19:41:20.556123Z","end":"2026-04-27T19:41:21.249377Z","steps":["trace[1238592164] 'agreement among raft nodes before linearized reading' (duration: 692.882218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:21.249436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:20.424631Z","time spent":"824.794242ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:41:21.249473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:20.556068Z","time spent":"693.39127ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T19:41:21.249096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.652324ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:21.24964Z","caller":"traceutil/trace.go:171","msg":"trace[1907655580] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:292; }","duration":"646.184597ms","start":"2026-04-27T19:41:20.603398Z","end":"2026-04-27T19:41:21.249582Z","steps":["trace[1907655580] 'agreement among raft nodes before linearized reading' (duration: 645.611723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:21.249886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157693731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T19:41:21.249958Z","caller":"traceutil/trace.go:171","msg":"trace[1959132777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:292; }","duration":"1.157801504s","start":"2026-04-27T19:41:20.092139Z","end":"2026-04-27T19:41:21.249941Z","steps":["trace[1959132777] 'agreement among raft nodes before linearized reading' (duration: 1.156834611s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:21.250011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:20.092115Z","time spent":"1.157883517s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:41:21.250194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:19.627138Z","time spent":"1.622183089s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:41:22.261551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.00724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:41:22.26214Z","caller":"traceutil/trace.go:171","msg":"trace[2136690590] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"1.0076881s","start":"2026-04-27T19:41:21.254413Z","end":"2026-04-27T19:41:22.262101Z","steps":["trace[2136690590] 'process raft request' (duration: 205.557808ms)","trace[2136690590] 'compare' (duration: 799.377575ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:41:22.262339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:21.254397Z","time spent":"1.007793202s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:41:22.323591Z","caller":"traceutil/trace.go:171","msg":"trace[1909493588] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"1.063239644s","start":"2026-04-27T19:41:21.260321Z","end":"2026-04-27T19:41:22.32356Z","steps":["trace[1909493588] 'process raft request' (duration: 1.063189053s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:22.323762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:21.260312Z","time spent":"1.063359817s","remote":"127.0.0.1:38872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:41:22.323768Z","caller":"traceutil/trace.go:171","msg":"trace[938767936] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"1.067818666s","start":"2026-04-27T19:41:21.255888Z","end":"2026-04-27T19:41:22.323706Z","steps":["trace[938767936] 'process raft request' (duration: 1.06758556s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:22.323991Z","caller":"traceutil/trace.go:171","msg":"trace[948536492] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"1.069387903s","start":"2026-04-27T19:41:21.254544Z","end":"2026-04-27T19:41:22.323932Z","steps":["trace[948536492] 'process raft request' (duration: 1.068734977s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:22.324235Z","caller":"traceutil/trace.go:171","msg":"trace[378800470] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"1.068947192s","start":"2026-04-27T19:41:21.255274Z","end":"2026-04-27T19:41:22.324221Z","steps":["trace[378800470] 'process raft request' (duration: 1.068122782s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:22.324252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:21.255874Z","time spent":"1.068145853s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:41:22.324318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:21.255253Z","time spent":"1.069021734s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:41:22.324266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:21.254519Z","time spent":"1.069656709s","remote":"127.0.0.1:38896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:41:22.759352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.112182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:41:22.759448Z","caller":"traceutil/trace.go:171","msg":"trace[1945373494] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"154.244374ms","start":"2026-04-27T19:41:22.605188Z","end":"2026-04-27T19:41:22.759432Z","steps":["trace[1945373494] 'process raft request' (duration: 18.991159ms)","trace[1945373494] 'compare' (duration: 134.967549ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:41:23.061945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.803033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:41:23.062149Z","caller":"traceutil/trace.go:171","msg":"trace[1908799446] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"456.838772ms","start":"2026-04-27T19:41:22.605276Z","end":"2026-04-27T19:41:23.062115Z","steps":["trace[1908799446] 'process raft request' (duration: 307.762662ms)","trace[1908799446] 'compare' (duration: 148.66912ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:23.062199Z","caller":"traceutil/trace.go:171","msg":"trace[560953045] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"298.704253ms","start":"2026-04-27T19:41:22.763477Z","end":"2026-04-27T19:41:23.062182Z","steps":["trace[560953045] 'process raft request' (duration: 298.662302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:23.062279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:22.605255Z","time spent":"456.964335ms","remote":"127.0.0.1:38788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:41:23.062335Z","caller":"traceutil/trace.go:171","msg":"trace[675257284] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"451.650036ms","start":"2026-04-27T19:41:22.610646Z","end":"2026-04-27T19:41:23.062296Z","steps":["trace[675257284] 'process raft request' (duration: 451.453701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:23.06241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:22.61064Z","time spent":"451.736228ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:41:23.062461Z","caller":"traceutil/trace.go:171","msg":"trace[110248684] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:330; }","duration":"433.278922ms","start":"2026-04-27T19:41:22.62914Z","end":"2026-04-27T19:41:23.062419Z","steps":["trace[110248684] 'read index received' (duration: 283.888135ms)","trace[110248684] 'applied index is now lower than readState.Index' (duration: 149.386887ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:23.062587Z","caller":"traceutil/trace.go:171","msg":"trace[578020260] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"452.078947ms","start":"2026-04-27T19:41:22.610496Z","end":"2026-04-27T19:41:23.062575Z","steps":["trace[578020260] 'process raft request' (duration: 451.544164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:23.062657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:22.610476Z","time spent":"452.139618ms","remote":"127.0.0.1:38788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:41:23.062677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.532177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-04-27T19:41:23.062681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.074661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-27T19:41:23.062717Z","caller":"traceutil/trace.go:171","msg":"trace[118031258] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:321; }","duration":"433.635ms","start":"2026-04-27T19:41:22.629069Z","end":"2026-04-27T19:41:23.062704Z","steps":["trace[118031258] 'agreement among raft nodes before linearized reading' (duration: 433.503697ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:23.06272Z","caller":"traceutil/trace.go:171","msg":"trace[406132539] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:321; }","duration":"358.153523ms","start":"2026-04-27T19:41:22.704555Z","end":"2026-04-27T19:41:23.062709Z","steps":["trace[406132539] 'agreement among raft nodes before linearized reading' (duration: 358.072871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:23.062726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.929761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"warn","ts":"2026-04-27T19:41:23.06276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:22.704529Z","time spent":"358.223604ms","remote":"127.0.0.1:38896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-04-27T19:41:23.06276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:22.629045Z","time spent":"433.706432ms","remote":"127.0.0.1:38896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-04-27T19:41:23.062768Z","caller":"traceutil/trace.go:171","msg":"trace[1620734456] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:321; }","duration":"290.006743ms","start":"2026-04-27T19:41:22.772749Z","end":"2026-04-27T19:41:23.062756Z","steps":["trace[1620734456] 'agreement among raft nodes before linearized reading' (duration: 289.911301ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:23.237538Z","caller":"traceutil/trace.go:171","msg":"trace[828741359] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"101.440616ms","start":"2026-04-27T19:41:23.136037Z","end":"2026-04-27T19:41:23.237478Z","steps":["trace[828741359] 'process raft request' (duration: 101.293632ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:23.237656Z","caller":"traceutil/trace.go:171","msg":"trace[358198983] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"101.6166ms","start":"2026-04-27T19:41:23.135999Z","end":"2026-04-27T19:41:23.237616Z","steps":["trace[358198983] 'process raft request' (duration: 25.990409ms)","trace[358198983] 'compare' (duration: 75.234162ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:23.388814Z","caller":"traceutil/trace.go:171","msg":"trace[42036101] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"138.937334ms","start":"2026-04-27T19:41:23.249847Z","end":"2026-04-27T19:41:23.388784Z","steps":["trace[42036101] 'process raft request' (duration: 96.898106ms)","trace[42036101] 'compare' (duration: 41.800332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:41:23.389034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.106807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-04-27T19:41:23.388915Z","caller":"traceutil/trace.go:171","msg":"trace[1048472428] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:353; }","duration":"138.987954ms","start":"2026-04-27T19:41:23.249903Z","end":"2026-04-27T19:41:23.388891Z","steps":["trace[1048472428] 'read index received' (duration: 10.792241ms)","trace[1048472428] 'applied index is now lower than readState.Index' (duration: 128.194673ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:23.389084Z","caller":"traceutil/trace.go:171","msg":"trace[1063144746] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"138.072773ms","start":"2026-04-27T19:41:23.250985Z","end":"2026-04-27T19:41:23.389057Z","steps":["trace[1063144746] 'process raft request' (duration: 137.709164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:23.389387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.403415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"warn","ts":"2026-04-27T19:41:23.389414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.386178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T19:41:23.389444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.440966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-04-27T19:41:23.38942Z","caller":"traceutil/trace.go:171","msg":"trace[1761451836] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"137.862288ms","start":"2026-04-27T19:41:23.25154Z","end":"2026-04-27T19:41:23.389403Z","steps":["trace[1761451836] 'process raft request' (duration: 137.294454ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:23.389528Z","caller":"traceutil/trace.go:171","msg":"trace[713090773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:344; }","duration":"135.523962ms","start":"2026-04-27T19:41:23.253968Z","end":"2026-04-27T19:41:23.389492Z","steps":["trace[713090773] 'agreement among raft nodes before linearized reading' (duration: 135.343087ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:23.389521Z","caller":"traceutil/trace.go:171","msg":"trace[270341826] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:344; }","duration":"139.514968ms","start":"2026-04-27T19:41:23.249987Z","end":"2026-04-27T19:41:23.389502Z","steps":["trace[270341826] 'agreement among raft nodes before linearized reading' (duration: 139.370404ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:23.389457Z","caller":"traceutil/trace.go:171","msg":"trace[1426692736] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:344; }","duration":"139.493467ms","start":"2026-04-27T19:41:23.249947Z","end":"2026-04-27T19:41:23.38944Z","steps":["trace[1426692736] 'agreement among raft nodes before linearized reading' (duration: 139.352164ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:23.389132Z","caller":"traceutil/trace.go:171","msg":"trace[372310091] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:344; }","duration":"139.244821ms","start":"2026-04-27T19:41:23.249874Z","end":"2026-04-27T19:41:23.389119Z","steps":["trace[372310091] 'agreement among raft nodes before linearized reading' (duration: 139.087637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:24.533915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.26914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:41:24.534065Z","caller":"traceutil/trace.go:171","msg":"trace[1773959582] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"201.653773ms","start":"2026-04-27T19:41:24.332385Z","end":"2026-04-27T19:41:24.534039Z","steps":["trace[1773959582] 'process raft request' (duration: 201.609962ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:24.534132Z","caller":"traceutil/trace.go:171","msg":"trace[300133630] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"203.169399ms","start":"2026-04-27T19:41:24.330927Z","end":"2026-04-27T19:41:24.534096Z","steps":["trace[300133630] 'process raft request' (duration: 92.657323ms)","trace[300133630] 'compare' (duration: 110.159708ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:32.066174Z","caller":"traceutil/trace.go:171","msg":"trace[2128253757] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"114.896882ms","start":"2026-04-27T19:41:31.951237Z","end":"2026-04-27T19:41:32.066134Z","steps":["trace[2128253757] 'process raft request' (duration: 93.467003ms)","trace[2128253757] 'compare' (duration: 21.179953ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:32.068792Z","caller":"traceutil/trace.go:171","msg":"trace[2015643990] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"111.974521ms","start":"2026-04-27T19:41:31.956798Z","end":"2026-04-27T19:41:32.068772Z","steps":["trace[2015643990] 'process raft request' (duration: 111.909739ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:32.06897Z","caller":"traceutil/trace.go:171","msg":"trace[1235556808] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"115.701541ms","start":"2026-04-27T19:41:31.953224Z","end":"2026-04-27T19:41:32.068925Z","steps":["trace[1235556808] 'process raft request' (duration: 115.274711ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:32.069175Z","caller":"traceutil/trace.go:171","msg":"trace[1553811279] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"113.358934ms","start":"2026-04-27T19:41:31.955795Z","end":"2026-04-27T19:41:32.069154Z","steps":["trace[1553811279] 'process raft request' (duration: 112.866502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:42.685413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.975615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-04-27T19:41:42.685516Z","caller":"traceutil/trace.go:171","msg":"trace[116391609] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:433; }","duration":"101.128668ms","start":"2026-04-27T19:41:42.584363Z","end":"2026-04-27T19:41:42.685492Z","steps":["trace[116391609] 'range keys from in-memory index tree' (duration: 100.789371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:46.897364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.326383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:41:46.89749Z","caller":"traceutil/trace.go:171","msg":"trace[25701563] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"573.508666ms","start":"2026-04-27T19:41:46.323952Z","end":"2026-04-27T19:41:46.89746Z","steps":["trace[25701563] 'process raft request' (duration: 77.976738ms)","trace[25701563] 'compare' (duration: 495.1983ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:41:46.897569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:46.323936Z","time spent":"573.600529ms","remote":"127.0.0.1:39154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:41:46.8983Z","caller":"traceutil/trace.go:171","msg":"trace[1083730835] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"532.855052ms","start":"2026-04-27T19:41:46.365419Z","end":"2026-04-27T19:41:46.898274Z","steps":["trace[1083730835] 'process raft request' (duration: 532.405021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:46.898504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:46.365389Z","time spent":"532.991366ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:41:46.898793Z","caller":"traceutil/trace.go:171","msg":"trace[892429139] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"516.281151ms","start":"2026-04-27T19:41:46.381946Z","end":"2026-04-27T19:41:46.898227Z","steps":["trace[892429139] 'process raft request' (duration: 516.25054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:46.899619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:46.381922Z","time spent":"517.654244ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:41:47.258Z","caller":"traceutil/trace.go:171","msg":"trace[2129447891] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"832.060457ms","start":"2026-04-27T19:41:46.425899Z","end":"2026-04-27T19:41:47.25796Z","steps":["trace[2129447891] 'process raft request' (duration: 744.829755ms)","trace[2129447891] 'compare' (duration: 86.545746ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:47.258097Z","caller":"traceutil/trace.go:171","msg":"trace[1238591545] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"675.899085ms","start":"2026-04-27T19:41:46.582126Z","end":"2026-04-27T19:41:47.258025Z","steps":["trace[1238591545] 'process raft request' (duration: 675.388073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:47.258234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:46.425872Z","time spent":"832.289393ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:41:47.258366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:46.582102Z","time spent":"676.176983ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:41:47.258903Z","caller":"traceutil/trace.go:171","msg":"trace[1782834455] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"575.414533ms","start":"2026-04-27T19:41:46.683461Z","end":"2026-04-27T19:41:47.258876Z","steps":["trace[1782834455] 'process raft request' (duration: 574.110732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:47.259041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:46.683437Z","time spent":"575.532576ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4558,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:41:47.259342Z","caller":"traceutil/trace.go:171","msg":"trace[1053875111] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"476.818686ms","start":"2026-04-27T19:41:46.782512Z","end":"2026-04-27T19:41:47.259331Z","steps":["trace[1053875111] 'process raft request' (duration: 476.377005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:47.259406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:46.782486Z","time spent":"476.888158ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:41:47.25946Z","caller":"traceutil/trace.go:171","msg":"trace[1766914619] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:587; }","duration":"820.674091ms","start":"2026-04-27T19:41:46.438525Z","end":"2026-04-27T19:41:47.259199Z","steps":["trace[1766914619] 'read index received' (duration: 459.24825ms)","trace[1766914619] 'applied index is now lower than readState.Index' (duration: 361.424531ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:41:47.261303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.826454ms","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-04-27T19:41:47.261766Z","caller":"traceutil/trace.go:171","msg":"trace[2070044408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"823.229684ms","start":"2026-04-27T19:41:46.438415Z","end":"2026-04-27T19:41:47.261645Z","steps":["trace[2070044408] 'agreement among raft nodes before linearized reading' (duration: 821.636436ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:47.261963Z","caller":"traceutil/trace.go:171","msg":"trace[819888466] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"331.194319ms","start":"2026-04-27T19:41:46.930753Z","end":"2026-04-27T19:41:47.261947Z","steps":["trace[819888466] 'process raft request' (duration: 330.921202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:47.262138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:46.930725Z","time spent":"331.275731ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:41:47.262206Z","caller":"traceutil/trace.go:171","msg":"trace[411308678] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"315.045388ms","start":"2026-04-27T19:41:46.947145Z","end":"2026-04-27T19:41:47.26219Z","steps":["trace[411308678] 'process raft request' (duration: 314.588497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:47.261945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:46.438395Z","time spent":"823.537271ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T19:41:47.262301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:46.947128Z","time spent":"315.12131ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:41:47.262437Z","caller":"traceutil/trace.go:171","msg":"trace[1245539158] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"280.627365ms","start":"2026-04-27T19:41:46.981764Z","end":"2026-04-27T19:41:47.262392Z","steps":["trace[1245539158] 'process raft request' (duration: 280.01267ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:47.26469Z","caller":"traceutil/trace.go:171","msg":"trace[45571979] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"182.020827ms","start":"2026-04-27T19:41:47.082653Z","end":"2026-04-27T19:41:47.264674Z","steps":["trace[45571979] 'process raft request' (duration: 179.229619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:47.271105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.023997ms","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-04-27T19:41:47.271221Z","caller":"traceutil/trace.go:171","msg":"trace[2029882964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"533.185441ms","start":"2026-04-27T19:41:46.738019Z","end":"2026-04-27T19:41:47.271205Z","steps":["trace[2029882964] 'agreement among raft nodes before linearized reading' (duration: 533.001366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:47.271301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:46.738Z","time spent":"533.289254ms","remote":"127.0.0.1:38960","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-04-27T19:41:47.271219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.405282ms","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-04-27T19:41:47.271494Z","caller":"traceutil/trace.go:171","msg":"trace[933107193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"527.691428ms","start":"2026-04-27T19:41:46.743752Z","end":"2026-04-27T19:41:47.271443Z","steps":["trace[933107193] 'agreement among raft nodes before linearized reading' (duration: 527.411172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:47.27166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:46.743732Z","time spent":"527.844162ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T19:41:49.494181Z","caller":"traceutil/trace.go:171","msg":"trace[1108586298] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"114.095923ms","start":"2026-04-27T19:41:49.380049Z","end":"2026-04-27T19:41:49.494145Z","steps":["trace[1108586298] 'read index received' (duration: 112.178336ms)","trace[1108586298] 'applied index is now lower than readState.Index' (duration: 1.916407ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:49.494473Z","caller":"traceutil/trace.go:171","msg":"trace[2063705923] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"122.178007ms","start":"2026-04-27T19:41:49.372267Z","end":"2026-04-27T19:41:49.494445Z","steps":["trace[2063705923] 'process raft request' (duration: 120.809585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:49.494538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.455982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-27T19:41:49.494626Z","caller":"traceutil/trace.go:171","msg":"trace[1343138549] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:606; }","duration":"114.608475ms","start":"2026-04-27T19:41:49.379999Z","end":"2026-04-27T19:41:49.494607Z","steps":["trace[1343138549] 'agreement among raft nodes before linearized reading' (duration: 114.294128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:49.63154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.969065ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-27T19:41:53.661936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.286615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:41:53.661967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.946838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:53.662004Z","caller":"traceutil/trace.go:171","msg":"trace[501623074] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:637; }","duration":"232.395837ms","start":"2026-04-27T19:41:53.429593Z","end":"2026-04-27T19:41:53.661989Z","steps":["trace[501623074] 'range keys from in-memory index tree' (duration: 232.210343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.662005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.619365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:53.662032Z","caller":"traceutil/trace.go:171","msg":"trace[789394099] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:637; }","duration":"382.05157ms","start":"2026-04-27T19:41:53.279963Z","end":"2026-04-27T19:41:53.662015Z","steps":["trace[789394099] 'range keys from in-memory index tree' (duration: 381.808245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.661989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.24174Z","time spent":"420.242736ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-27T19:41:53.662055Z","caller":"traceutil/trace.go:171","msg":"trace[1546946685] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:637; }","duration":"356.694857ms","start":"2026-04-27T19:41:53.305342Z","end":"2026-04-27T19:41:53.662037Z","steps":["trace[1546946685] 'count revisions from in-memory index tree' (duration: 356.571564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.662162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.305328Z","time spent":"356.81469ms","remote":"127.0.0.1:59492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:41:53.662306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.153798ms","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-04-27T19:41:53.662342Z","caller":"traceutil/trace.go:171","msg":"trace[454340838] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:637; }","duration":"172.23491ms","start":"2026-04-27T19:41:53.490098Z","end":"2026-04-27T19:41:53.662333Z","steps":["trace[454340838] 'count revisions from in-memory index tree' (duration: 172.103967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.662369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.811702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:53.662447Z","caller":"traceutil/trace.go:171","msg":"trace[1345482381] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:637; }","duration":"409.947336ms","start":"2026-04-27T19:41:53.252478Z","end":"2026-04-27T19:41:53.662426Z","steps":["trace[1345482381] 'range keys from in-memory index tree' (duration: 409.718461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.662471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.685124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:53.6625Z","caller":"traceutil/trace.go:171","msg":"trace[1650543827] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:637; }","duration":"232.760556ms","start":"2026-04-27T19:41:53.429733Z","end":"2026-04-27T19:41:53.662493Z","steps":["trace[1650543827] 'count revisions from in-memory index tree' (duration: 232.554191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.662496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.252472Z","time spent":"410.007478ms","remote":"127.0.0.1:59476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-27T19:41:53.662497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.013893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:53.662576Z","caller":"traceutil/trace.go:171","msg":"trace[1676940936] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:637; }","duration":"411.120896ms","start":"2026-04-27T19:41:53.251443Z","end":"2026-04-27T19:41:53.662563Z","steps":["trace[1676940936] 'count revisions from in-memory index tree' (duration: 410.959101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.662604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.315385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:53.662632Z","caller":"traceutil/trace.go:171","msg":"trace[509882032] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:637; }","duration":"220.357916ms","start":"2026-04-27T19:41:53.442266Z","end":"2026-04-27T19:41:53.662624Z","steps":["trace[509882032] 'count revisions from in-memory index tree' (duration: 220.272753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.662675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.251428Z","time spent":"411.232488ms","remote":"127.0.0.1:59476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:41:53.66274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.084984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:41:53.66278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.685119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:53.6628Z","caller":"traceutil/trace.go:171","msg":"trace[741753795] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:637; }","duration":"219.7162ms","start":"2026-04-27T19:41:53.443077Z","end":"2026-04-27T19:41:53.662793Z","steps":["trace[741753795] 'range keys from in-memory index tree' (duration: 219.650218ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:53.6628Z","caller":"traceutil/trace.go:171","msg":"trace[1711137696] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:637; }","duration":"411.147616ms","start":"2026-04-27T19:41:53.251638Z","end":"2026-04-27T19:41:53.662786Z","steps":["trace[1711137696] 'range keys from in-memory index tree' (duration: 411.044133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.662886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.251619Z","time spent":"411.252328ms","remote":"127.0.0.1:59476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-27T19:41:53.66292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.89018ms","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-04-27T19:41:53.662949Z","caller":"traceutil/trace.go:171","msg":"trace[570639326] 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:637; }","duration":"199.935291ms","start":"2026-04-27T19:41:53.463005Z","end":"2026-04-27T19:41:53.662941Z","steps":["trace[570639326] 'count revisions from in-memory index tree' (duration: 199.858039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.662754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.463659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:41:53.662163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.373417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:53.662999Z","caller":"traceutil/trace.go:171","msg":"trace[1412576086] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:637; }","duration":"220.716655ms","start":"2026-04-27T19:41:53.442269Z","end":"2026-04-27T19:41:53.662985Z","steps":["trace[1412576086] 'range keys from in-memory index tree' (duration: 220.393096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.662997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.427484ms","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-04-27T19:41:53.663031Z","caller":"traceutil/trace.go:171","msg":"trace[200626798] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:637; }","duration":"233.290119ms","start":"2026-04-27T19:41:53.429732Z","end":"2026-04-27T19:41:53.663022Z","steps":["trace[200626798] 'range keys from in-memory index tree' (duration: 232.283755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.66305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.447052ms","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-04-27T19:41:53.663066Z","caller":"traceutil/trace.go:171","msg":"trace[1618725025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"345.619488ms","start":"2026-04-27T19:41:53.317425Z","end":"2026-04-27T19:41:53.663045Z","steps":["trace[1618725025] 'range keys from in-memory index tree' (duration: 345.220779ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:53.663111Z","caller":"traceutil/trace.go:171","msg":"trace[4702604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"342.534643ms","start":"2026-04-27T19:41:53.320562Z","end":"2026-04-27T19:41:53.663097Z","steps":["trace[4702604] 'range keys from in-memory index tree' (duration: 342.331169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.663136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.320552Z","time spent":"342.578975ms","remote":"127.0.0.1:38960","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-04-27T19:41:53.663138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.317407Z","time spent":"345.718721ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T19:41:53.663172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.045464ms","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-04-27T19:41:53.663204Z","caller":"traceutil/trace.go:171","msg":"trace[1687173402] 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:637; }","duration":"200.111665ms","start":"2026-04-27T19:41:53.463085Z","end":"2026-04-27T19:41:53.663197Z","steps":["trace[1687173402] 'range keys from in-memory index tree' (duration: 199.961182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.663068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.058184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:53.663298Z","caller":"traceutil/trace.go:171","msg":"trace[1998956582] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:637; }","duration":"383.326361ms","start":"2026-04-27T19:41:53.279963Z","end":"2026-04-27T19:41:53.663289Z","steps":["trace[1998956582] 'count revisions from in-memory index tree' (duration: 382.934302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.6633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.084315ms","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-04-27T19:41:53.663318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.279947Z","time spent":"383.363262ms","remote":"127.0.0.1:59482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:41:53.663349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.253984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:53.663375Z","caller":"traceutil/trace.go:171","msg":"trace[606470932] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:637; }","duration":"173.304126ms","start":"2026-04-27T19:41:53.490065Z","end":"2026-04-27T19:41:53.663369Z","steps":["trace[606470932] 'range keys from in-memory index tree' (duration: 173.168952ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:53.663324Z","caller":"traceutil/trace.go:171","msg":"trace[1060965330] 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:637; }","duration":"200.121356ms","start":"2026-04-27T19:41:53.463196Z","end":"2026-04-27T19:41:53.663317Z","steps":["trace[1060965330] 'range keys from in-memory index tree' (duration: 200.058664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.663612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.66974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:53.663666Z","caller":"traceutil/trace.go:171","msg":"trace[268184847] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:637; }","duration":"357.929418ms","start":"2026-04-27T19:41:53.30572Z","end":"2026-04-27T19:41:53.663649Z","steps":["trace[268184847] 'range keys from in-memory index tree' (duration: 357.615149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.663706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.30571Z","time spent":"357.982069ms","remote":"127.0.0.1:59482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-27T19:41:53.663911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.098882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:41:53.662082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.279947Z","time spent":"382.118563ms","remote":"127.0.0.1:59482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-27T19:41:53.663941Z","caller":"traceutil/trace.go:171","msg":"trace[803807534] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:637; }","duration":"173.131002ms","start":"2026-04-27T19:41:53.490803Z","end":"2026-04-27T19:41:53.663934Z","steps":["trace[803807534] 'range keys from in-memory index tree' (duration: 172.978358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.661938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.776968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:53.664038Z","caller":"traceutil/trace.go:171","msg":"trace[2142657129] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:637; }","duration":"358.892051ms","start":"2026-04-27T19:41:53.305139Z","end":"2026-04-27T19:41:53.664031Z","steps":["trace[2142657129] 'range keys from in-memory index tree' (duration: 356.695197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.66406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.305023Z","time spent":"359.028744ms","remote":"127.0.0.1:59492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-27T19:41:53.664244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.529085ms","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-04-27T19:41:53.664268Z","caller":"traceutil/trace.go:171","msg":"trace[1930147888] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:637; }","duration":"456.556265ms","start":"2026-04-27T19:41:53.207706Z","end":"2026-04-27T19:41:53.664263Z","steps":["trace[1930147888] 'range keys from in-memory index tree' (duration: 456.486563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.664286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.207675Z","time spent":"456.605177ms","remote":"127.0.0.1:59458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-27T19:41:53.664362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.860992ms","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-04-27T19:41:53.664379Z","caller":"traceutil/trace.go:171","msg":"trace[623535981] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:637; }","duration":"456.953925ms","start":"2026-04-27T19:41:53.207421Z","end":"2026-04-27T19:41:53.664375Z","steps":["trace[623535981] 'count revisions from in-memory index tree' (duration: 456.826911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.664393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.207412Z","time spent":"456.977415ms","remote":"127.0.0.1:59458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:41:53.665205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.326225ms","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-04-27T19:41:53.665302Z","caller":"traceutil/trace.go:171","msg":"trace[1352526183] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:637; }","duration":"457.982489ms","start":"2026-04-27T19:41:53.207257Z","end":"2026-04-27T19:41:53.665239Z","steps":["trace[1352526183] 'range keys from in-memory index tree' (duration: 455.222742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.665396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.207235Z","time spent":"458.150923ms","remote":"127.0.0.1:59458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-27T19:41:53.665402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.292352ms","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-04-27T19:41:53.665501Z","caller":"traceutil/trace.go:171","msg":"trace[1905588280] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"321.662109ms","start":"2026-04-27T19:41:53.343817Z","end":"2026-04-27T19:41:53.665479Z","steps":["trace[1905588280] 'range keys from in-memory index tree' (duration: 318.919853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:53.665621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.343777Z","time spent":"321.797712ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:41:54.512023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174728898249836174,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T19:41:54.590512Z","caller":"traceutil/trace.go:171","msg":"trace[1035800203] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"593.647005ms","start":"2026-04-27T19:41:53.996845Z","end":"2026-04-27T19:41:54.590492Z","steps":["trace[1035800203] 'process raft request' (duration: 593.527533ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:54.590603Z","caller":"traceutil/trace.go:171","msg":"trace[1422161760] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:671; }","duration":"579.265106ms","start":"2026-04-27T19:41:54.011321Z","end":"2026-04-27T19:41:54.590586Z","steps":["trace[1422161760] 'read index received' (duration: 579.258045ms)","trace[1422161760] 'applied index is now lower than readState.Index' (duration: 5.621µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:41:54.590644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:53.996826Z","time spent":"593.746727ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:41:54.590779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.348478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:54.59085Z","caller":"traceutil/trace.go:171","msg":"trace[1436995416] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:645; }","duration":"579.45105ms","start":"2026-04-27T19:41:54.011366Z","end":"2026-04-27T19:41:54.590817Z","steps":["trace[1436995416] 'agreement among raft nodes before linearized reading' (duration: 579.311887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:54.590888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.011345Z","time spent":"579.532774ms","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-04-27T19:41:54.590933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.438097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:54.590961Z","caller":"traceutil/trace.go:171","msg":"trace[1360155850] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:645; }","duration":"540.477277ms","start":"2026-04-27T19:41:54.050476Z","end":"2026-04-27T19:41:54.590953Z","steps":["trace[1360155850] 'agreement among raft nodes before linearized reading' (duration: 540.439246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:54.590982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.050464Z","time spent":"540.512629ms","remote":"127.0.0.1:59656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-27T19:41:54.590954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.337689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:41:54.590962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.484249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:54.591053Z","caller":"traceutil/trace.go:171","msg":"trace[1019062993] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:645; }","duration":"541.469092ms","start":"2026-04-27T19:41:54.04956Z","end":"2026-04-27T19:41:54.591029Z","steps":["trace[1019062993] 'agreement among raft nodes before linearized reading' (duration: 541.345029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:54.591028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.844112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:41:54.591089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.42349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:41:54.591114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.049544Z","time spent":"541.557574ms","remote":"127.0.0.1:59656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:41:54.591139Z","caller":"traceutil/trace.go:171","msg":"trace[733849388] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:645; }","duration":"561.000025ms","start":"2026-04-27T19:41:54.030112Z","end":"2026-04-27T19:41:54.591112Z","steps":["trace[733849388] 'agreement among raft nodes before linearized reading' (duration: 560.78025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:54.590896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.197526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:54.591204Z","caller":"traceutil/trace.go:171","msg":"trace[815503762] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:645; }","duration":"541.507243ms","start":"2026-04-27T19:41:54.049688Z","end":"2026-04-27T19:41:54.591195Z","steps":["trace[815503762] 'agreement among raft nodes before linearized reading' (duration: 541.180174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:54.591214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.273192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:41:54.591219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.359274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:41:54.591223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.030101Z","time spent":"561.103128ms","remote":"127.0.0.1:59650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"info","ts":"2026-04-27T19:41:54.591252Z","caller":"traceutil/trace.go:171","msg":"trace[1058807728] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:645; }","duration":"561.336123ms","start":"2026-04-27T19:41:54.029905Z","end":"2026-04-27T19:41:54.591241Z","steps":["trace[1058807728] 'agreement among raft nodes before linearized reading' (duration: 561.278972ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:54.591257Z","caller":"traceutil/trace.go:171","msg":"trace[1896129005] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:645; }","duration":"561.417535ms","start":"2026-04-27T19:41:54.02983Z","end":"2026-04-27T19:41:54.591247Z","steps":["trace[1896129005] 'agreement among raft nodes before linearized reading' (duration: 561.359364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:54.591277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.354706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:54.59131Z","caller":"traceutil/trace.go:171","msg":"trace[975190342] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:645; }","duration":"526.387497ms","start":"2026-04-27T19:41:54.064917Z","end":"2026-04-27T19:41:54.591305Z","steps":["trace[975190342] 'agreement among raft nodes before linearized reading' (duration: 526.342816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:54.591305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.029818Z","time spent":"561.478397ms","remote":"127.0.0.1:59650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-04-27T19:41:54.591333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.064908Z","time spent":"526.419087ms","remote":"127.0.0.1:59666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-04-27T19:41:54.59132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.02989Z","time spent":"561.416016ms","remote":"127.0.0.1:59650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:41:54.590963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.617675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:41:54.591385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.039486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w2svm\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-27T19:41:54.59145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.209576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:54.591459Z","caller":"traceutil/trace.go:171","msg":"trace[1203446947] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w2svm; range_end:; response_count:1; response_revision:645; }","duration":"336.147189ms","start":"2026-04-27T19:41:54.2553Z","end":"2026-04-27T19:41:54.591447Z","steps":["trace[1203446947] 'agreement among raft nodes before linearized reading' (duration: 335.886093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:54.59147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.229648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.57\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-27T19:41:54.591244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.049674Z","time spent":"541.560604ms","remote":"127.0.0.1:59656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-04-27T19:41:54.591499Z","caller":"traceutil/trace.go:171","msg":"trace[288642338] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:645; }","duration":"527.278909ms","start":"2026-04-27T19:41:54.064205Z","end":"2026-04-27T19:41:54.591484Z","steps":["trace[288642338] 'agreement among raft nodes before linearized reading' (duration: 527.219348ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:54.591505Z","caller":"traceutil/trace.go:171","msg":"trace[1280177527] range","detail":"{range_begin:/registry/masterleases/199.19.213.57; range_end:; response_count:1; response_revision:645; }","duration":"513.281309ms","start":"2026-04-27T19:41:54.078215Z","end":"2026-04-27T19:41:54.591496Z","steps":["trace[1280177527] 'agreement among raft nodes before linearized reading' (duration: 513.164227ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:54.591397Z","caller":"traceutil/trace.go:171","msg":"trace[1568186208] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:645; }","duration":"580.090397ms","start":"2026-04-27T19:41:54.011294Z","end":"2026-04-27T19:41:54.591384Z","steps":["trace[1568186208] 'agreement among raft nodes before linearized reading' (duration: 579.601325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:54.591539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.064185Z","time spent":"527.34251ms","remote":"127.0.0.1:59666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-04-27T19:41:54.591568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.255281Z","time spent":"336.268773ms","remote":"127.0.0.1:38872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-w2svm\" "} {"level":"warn","ts":"2026-04-27T19:41:54.591581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.011284Z","time spent":"580.282011ms","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:41:54.591098Z","caller":"traceutil/trace.go:171","msg":"trace[586055759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"412.645063ms","start":"2026-04-27T19:41:54.178425Z","end":"2026-04-27T19:41:54.59107Z","steps":["trace[586055759] 'agreement among raft nodes before linearized reading' (duration: 412.385886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:54.591538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.078204Z","time spent":"513.328251ms","remote":"127.0.0.1:38728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.57\" "} {"level":"warn","ts":"2026-04-27T19:41:54.591654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.178368Z","time spent":"413.278348ms","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T19:41:54.591156Z","caller":"traceutil/trace.go:171","msg":"trace[951128942] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:645; }","duration":"579.489042ms","start":"2026-04-27T19:41:54.011632Z","end":"2026-04-27T19:41:54.591121Z","steps":["trace[951128942] 'agreement among raft nodes before linearized reading' (duration: 579.423191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:54.591826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.01162Z","time spent":"580.190889ms","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-04-27T19:41:54.591881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.665313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:54.591937Z","caller":"traceutil/trace.go:171","msg":"trace[524671548] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:645; }","duration":"527.755311ms","start":"2026-04-27T19:41:54.064173Z","end":"2026-04-27T19:41:54.591928Z","steps":["trace[524671548] 'agreement among raft nodes before linearized reading' (duration: 526.598732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:54.591967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.064159Z","time spent":"527.799551ms","remote":"127.0.0.1:59666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:41:55.249874Z","caller":"traceutil/trace.go:171","msg":"trace[1268804251] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"645.095531ms","start":"2026-04-27T19:41:54.604743Z","end":"2026-04-27T19:41:55.249838Z","steps":["trace[1268804251] 'process raft request' (duration: 644.965007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:55.250009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.60473Z","time spent":"645.211294ms","remote":"127.0.0.1:38872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:41:55.262223Z","caller":"traceutil/trace.go:171","msg":"trace[1488087451] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"485.035794ms","start":"2026-04-27T19:41:54.777157Z","end":"2026-04-27T19:41:55.262193Z","steps":["trace[1488087451] 'process raft request' (duration: 484.85929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:55.262383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:54.777135Z","time spent":"485.162098ms","remote":"127.0.0.1:38728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:41:56.185968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174728898249836187,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:41:56.686457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174728898249836187,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:41:56.97187Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.287514986s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:41:56.972281Z","caller":"traceutil/trace.go:171","msg":"trace[724139052] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"1.28636846s","start":"2026-04-27T19:41:55.68589Z","end":"2026-04-27T19:41:56.972258Z","steps":["trace[724139052] 'read index received' (duration: 1.286092312s)","trace[724139052] 'applied index is now lower than readState.Index' (duration: 275.078µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:56.972452Z","caller":"traceutil/trace.go:171","msg":"trace[2070911371] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.288202524s","start":"2026-04-27T19:41:55.684226Z","end":"2026-04-27T19:41:56.972429Z","steps":["trace[2070911371] 'process raft request' (duration: 1.287815475s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:56.972608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.286700617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T19:41:56.972616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:55.684202Z","time spent":"1.288317316s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:41:56.972632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.286701567s","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-04-27T19:41:56.972674Z","caller":"traceutil/trace.go:171","msg":"trace[401809973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"1.28679624s","start":"2026-04-27T19:41:55.685862Z","end":"2026-04-27T19:41:56.972658Z","steps":["trace[401809973] 'agreement among raft nodes before linearized reading' (duration: 1.286589445s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:56.972688Z","caller":"traceutil/trace.go:171","msg":"trace[2045171160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"1.28678619s","start":"2026-04-27T19:41:55.685886Z","end":"2026-04-27T19:41:56.972673Z","steps":["trace[2045171160] 'agreement among raft nodes before linearized reading' (duration: 1.286621026s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:56.972723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:55.685854Z","time spent":"1.286855861s","remote":"127.0.0.1:38960","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-04-27T19:41:56.97273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:55.685873Z","time spent":"1.286846401s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:41:56.972725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.581966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:56.972805Z","caller":"traceutil/trace.go:171","msg":"trace[1860806085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"793.719599ms","start":"2026-04-27T19:41:56.179065Z","end":"2026-04-27T19:41:56.972784Z","steps":["trace[1860806085] 'agreement among raft nodes before linearized reading' (duration: 793.545825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:56.972791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277154326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T19:41:56.972851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:56.179045Z","time spent":"793.798152ms","remote":"127.0.0.1:38692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T19:41:56.972861Z","caller":"traceutil/trace.go:171","msg":"trace[735565274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"1.277258429s","start":"2026-04-27T19:41:55.695585Z","end":"2026-04-27T19:41:56.972844Z","steps":["trace[735565274] 'agreement among raft nodes before linearized reading' (duration: 1.277063944s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:56.972902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:55.695571Z","time spent":"1.27732212s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:41:56.972951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.019682ms","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-04-27T19:41:56.973005Z","caller":"traceutil/trace.go:171","msg":"trace[1285924978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:648; }","duration":"375.113444ms","start":"2026-04-27T19:41:56.597876Z","end":"2026-04-27T19:41:56.97299Z","steps":["trace[1285924978] 'agreement among raft nodes before linearized reading' (duration: 374.977521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:56.973047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:56.597857Z","time spent":"375.178625ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T19:42:13.503972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.924459ms","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":"warn","ts":"2026-04-27T19:42:13.504049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.199383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T19:42:13.504056Z","caller":"traceutil/trace.go:171","msg":"trace[162281313] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:760; }","duration":"463.059993ms","start":"2026-04-27T19:42:13.04098Z","end":"2026-04-27T19:42:13.50404Z","steps":["trace[162281313] 'count revisions from in-memory index tree' (duration: 462.847288ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:42:13.504124Z","caller":"traceutil/trace.go:171","msg":"trace[1582310162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"326.309565ms","start":"2026-04-27T19:42:13.177796Z","end":"2026-04-27T19:42:13.504106Z","steps":["trace[1582310162] 'range keys from in-memory index tree' (duration: 326.05612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:42:13.504172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:42:13.177786Z","time spent":"326.377498ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:42:13.504157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:42:13.040968Z","time spent":"463.168625ms","remote":"127.0.0.1:39220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":73,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:42:13.504215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.091868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T19:42:13.504298Z","caller":"traceutil/trace.go:171","msg":"trace[1346637925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"349.222331ms","start":"2026-04-27T19:42:13.15506Z","end":"2026-04-27T19:42:13.504282Z","steps":["trace[1346637925] 'range keys from in-memory index tree' (duration: 348.963395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:42:13.504336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:42:13.155038Z","time spent":"349.287892ms","remote":"127.0.0.1:38960","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-04-27T19:42:13.504497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.673456ms","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-04-27T19:42:13.504527Z","caller":"traceutil/trace.go:171","msg":"trace[1422605989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"362.726198ms","start":"2026-04-27T19:42:13.141792Z","end":"2026-04-27T19:42:13.504518Z","steps":["trace[1422605989] 'range keys from in-memory index tree' (duration: 362.544293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:42:13.504556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:42:13.141781Z","time spent":"362.764989ms","remote":"127.0.0.1:38960","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-04-27T19:42:13.504783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.81961ms","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-04-27T19:42:13.504846Z","caller":"traceutil/trace.go:171","msg":"trace[2114437127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"472.881672ms","start":"2026-04-27T19:42:13.031949Z","end":"2026-04-27T19:42:13.50483Z","steps":["trace[2114437127] 'range keys from in-memory index tree' (duration: 472.646086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:42:13.504878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:42:13.031895Z","time spent":"472.975593ms","remote":"127.0.0.1:38960","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-04-27T19:42:13.50538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.185703ms","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-04-27T19:42:13.505437Z","caller":"traceutil/trace.go:171","msg":"trace[530809866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"327.536425ms","start":"2026-04-27T19:42:13.177883Z","end":"2026-04-27T19:42:13.50542Z","steps":["trace[530809866] 'range keys from in-memory index tree' (duration: 326.016829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:42:13.505494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:42:13.17787Z","time spent":"327.605497ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T19:42:13.508381Z","caller":"traceutil/trace.go:171","msg":"trace[430528082] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"135.175759ms","start":"2026-04-27T19:42:13.373191Z","end":"2026-04-27T19:42:13.508366Z","steps":["trace[430528082] 'process raft request' (duration: 135.050846ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:42:13.513858Z","caller":"traceutil/trace.go:171","msg":"trace[369700197] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"136.90565ms","start":"2026-04-27T19:42:13.376938Z","end":"2026-04-27T19:42:13.513844Z","steps":["trace[369700197] 'process raft request' (duration: 136.610533ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:42:48.036432Z","caller":"traceutil/trace.go:171","msg":"trace[1896964950] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"218.953551ms","start":"2026-04-27T19:42:47.817455Z","end":"2026-04-27T19:42:48.036409Z","steps":["trace[1896964950] 'read index received' (duration: 218.718775ms)","trace[1896964950] 'applied index is now lower than readState.Index' (duration: 233.806µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:42:48.036519Z","caller":"traceutil/trace.go:171","msg":"trace[18077671] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"229.846175ms","start":"2026-04-27T19:42:47.806643Z","end":"2026-04-27T19:42:48.03649Z","steps":["trace[18077671] 'process raft request' (duration: 229.593049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:42:48.036654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.171156ms","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-04-27T19:42:48.036721Z","caller":"traceutil/trace.go:171","msg":"trace[1907268939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:974; }","duration":"219.288929ms","start":"2026-04-27T19:42:47.817416Z","end":"2026-04-27T19:42:48.036705Z","steps":["trace[1907268939] 'agreement among raft nodes before linearized reading' (duration: 219.088194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:42:48.036742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.140915ms","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-04-27T19:42:48.036795Z","caller":"traceutil/trace.go:171","msg":"trace[1774496835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:974; }","duration":"200.236947ms","start":"2026-04-27T19:42:47.836544Z","end":"2026-04-27T19:42:48.036781Z","steps":["trace[1774496835] 'agreement among raft nodes before linearized reading' (duration: 200.020872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:42:48.036803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.145095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T19:42:48.036857Z","caller":"traceutil/trace.go:171","msg":"trace[1452467634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:974; }","duration":"193.256977ms","start":"2026-04-27T19:42:47.843586Z","end":"2026-04-27T19:42:48.036843Z","steps":["trace[1452467634] 'agreement among raft nodes before linearized reading' (duration: 193.055883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:42:48.036967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.539364ms","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-04-27T19:42:48.037002Z","caller":"traceutil/trace.go:171","msg":"trace[648538255] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:974; }","duration":"193.593136ms","start":"2026-04-27T19:42:47.8434Z","end":"2026-04-27T19:42:48.036993Z","steps":["trace[648538255] 'agreement among raft nodes before linearized reading' (duration: 193.510094ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:42:48.162979Z","caller":"traceutil/trace.go:171","msg":"trace[922432604] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"121.082417ms","start":"2026-04-27T19:42:48.041877Z","end":"2026-04-27T19:42:48.16296Z","steps":["trace[922432604] 'process raft request' (duration: 121.031016ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:42:48.163106Z","caller":"traceutil/trace.go:171","msg":"trace[612733677] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"121.22603ms","start":"2026-04-27T19:42:48.041842Z","end":"2026-04-27T19:42:48.163068Z","steps":["trace[612733677] 'process raft request' (duration: 74.565478ms)","trace[612733677] 'compare' (duration: 46.377446ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:42:48.163759Z","caller":"traceutil/trace.go:171","msg":"trace[1763490894] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"119.527069ms","start":"2026-04-27T19:42:48.044218Z","end":"2026-04-27T19:42:48.163745Z","steps":["trace[1763490894] 'process raft request' (duration: 118.718359ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:42:48.672971Z","caller":"traceutil/trace.go:171","msg":"trace[2057449516] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"206.512489ms","start":"2026-04-27T19:42:48.466436Z","end":"2026-04-27T19:42:48.672949Z","steps":["trace[2057449516] 'read index received' (duration: 184.762001ms)","trace[2057449516] 'applied index is now lower than readState.Index' (duration: 21.749408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:42:48.673093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.639952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:42:48.673118Z","caller":"traceutil/trace.go:171","msg":"trace[658747947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"206.703124ms","start":"2026-04-27T19:42:48.466406Z","end":"2026-04-27T19:42:48.673109Z","steps":["trace[658747947] 'agreement among raft nodes before linearized reading' (duration: 206.619842ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:42:48.673235Z","caller":"traceutil/trace.go:171","msg":"trace[697146400] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"312.858949ms","start":"2026-04-27T19:42:48.360348Z","end":"2026-04-27T19:42:48.673207Z","steps":["trace[697146400] 'process raft request' (duration: 290.874106ms)","trace[697146400] 'compare' (duration: 21.487121ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:42:48.673368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:42:48.360319Z","time spent":"312.973362ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:42:49.076476Z","caller":"traceutil/trace.go:171","msg":"trace[1024250935] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"304.129987ms","start":"2026-04-27T19:42:48.772324Z","end":"2026-04-27T19:42:49.076454Z","steps":["trace[1024250935] 'process raft request' (duration: 215.611989ms)","trace[1024250935] 'compare' (duration: 88.125378ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:42:49.076586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:42:48.772304Z","time spent":"304.224549ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:42:49.285352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.896984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:42:49.285438Z","caller":"traceutil/trace.go:171","msg":"trace[2020810467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"107.046048ms","start":"2026-04-27T19:42:49.178376Z","end":"2026-04-27T19:42:49.285422Z","steps":["trace[2020810467] 'range keys from in-memory index tree' (duration: 106.805612ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:10.869394Z","caller":"traceutil/trace.go:171","msg":"trace[2085442986] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"101.176214ms","start":"2026-04-27T19:43:10.768198Z","end":"2026-04-27T19:43:10.869374Z","steps":["trace[2085442986] 'process raft request' (duration: 101.064371ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:12.669555Z","caller":"traceutil/trace.go:171","msg":"trace[1439603076] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"516.107498ms","start":"2026-04-27T19:43:12.153406Z","end":"2026-04-27T19:43:12.669514Z","steps":["trace[1439603076] 'process raft request' (duration: 515.939504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:12.66974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:12.15338Z","time spent":"516.274093ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:43:12.669889Z","caller":"traceutil/trace.go:171","msg":"trace[912911226] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"496.559575ms","start":"2026-04-27T19:43:12.173291Z","end":"2026-04-27T19:43:12.66985Z","steps":["trace[912911226] 'read index received' (duration: 495.994061ms)","trace[912911226] 'applied index is now lower than readState.Index' (duration: 558.403µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:43:12.670063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.77817ms","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-04-27T19:43:12.670103Z","caller":"traceutil/trace.go:171","msg":"trace[248702145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1053; }","duration":"496.860862ms","start":"2026-04-27T19:43:12.173229Z","end":"2026-04-27T19:43:12.67009Z","steps":["trace[248702145] 'agreement among raft nodes before linearized reading' (duration: 496.728999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:12.670101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.073648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:43:12.670133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:12.173205Z","time spent":"496.921403ms","remote":"127.0.0.1:38960","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-04-27T19:43:12.670137Z","caller":"traceutil/trace.go:171","msg":"trace[573080289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1053; }","duration":"490.17167ms","start":"2026-04-27T19:43:12.179955Z","end":"2026-04-27T19:43:12.670127Z","steps":["trace[573080289] 'agreement among raft nodes before linearized reading' (duration: 490.074048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:12.670175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:12.179935Z","time spent":"490.231101ms","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:43:12.670261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.243148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T19:43:12.670287Z","caller":"traceutil/trace.go:171","msg":"trace[225590102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1053; }","duration":"393.29007ms","start":"2026-04-27T19:43:12.276989Z","end":"2026-04-27T19:43:12.670279Z","steps":["trace[225590102] 'agreement among raft nodes before linearized reading' (duration: 393.218028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:12.670318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:12.276977Z","time spent":"393.32993ms","remote":"127.0.0.1:38960","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-04-27T19:43:12.670457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.569704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:43:12.670534Z","caller":"traceutil/trace.go:171","msg":"trace[803485592] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1053; }","duration":"296.702317ms","start":"2026-04-27T19:43:12.373813Z","end":"2026-04-27T19:43:12.670515Z","steps":["trace[803485592] 'agreement among raft nodes before linearized reading' (duration: 296.570454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:12.670538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.140403ms","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-04-27T19:43:12.670576Z","caller":"traceutil/trace.go:171","msg":"trace[1522548343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1053; }","duration":"397.209035ms","start":"2026-04-27T19:43:12.273357Z","end":"2026-04-27T19:43:12.670566Z","steps":["trace[1522548343] 'agreement among raft nodes before linearized reading' (duration: 397.078832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:12.670605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:12.27334Z","time spent":"397.259185ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:43:12.670628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.064347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T19:43:12.670671Z","caller":"traceutil/trace.go:171","msg":"trace[436183058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1053; }","duration":"396.144679ms","start":"2026-04-27T19:43:12.274514Z","end":"2026-04-27T19:43:12.670658Z","steps":["trace[436183058] 'agreement among raft nodes before linearized reading' (duration: 396.040156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:12.670699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:12.274493Z","time spent":"396.199701ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:43:13.245696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.164911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:43:13.245812Z","caller":"traceutil/trace.go:171","msg":"trace[421526219] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"570.853607ms","start":"2026-04-27T19:43:12.674931Z","end":"2026-04-27T19:43:13.245785Z","steps":["trace[421526219] 'process raft request' (duration: 465.509111ms)","trace[421526219] 'compare' (duration: 104.898515ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:43:13.245946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:12.674914Z","time spent":"570.929709ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:43:13.336673Z","caller":"traceutil/trace.go:171","msg":"trace[604794298] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"660.051729ms","start":"2026-04-27T19:43:12.676597Z","end":"2026-04-27T19:43:13.336648Z","steps":["trace[604794298] 'process raft request' (duration: 659.985208ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:13.336977Z","caller":"traceutil/trace.go:171","msg":"trace[1503387457] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"660.257645ms","start":"2026-04-27T19:43:12.676702Z","end":"2026-04-27T19:43:13.336959Z","steps":["trace[1503387457] 'process raft request' (duration: 659.913766ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:13.336692Z","caller":"traceutil/trace.go:171","msg":"trace[1381782509] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"660.652175ms","start":"2026-04-27T19:43:12.676004Z","end":"2026-04-27T19:43:13.336656Z","steps":["trace[1381782509] 'process raft request' (duration: 660.456101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:13.337065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:12.676694Z","time spent":"660.328266ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:43:13.337058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:12.676582Z","time spent":"660.352727ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:43:13.337139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:12.675981Z","time spent":"661.078855ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:43:13.337209Z","caller":"traceutil/trace.go:171","msg":"trace[1209677544] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1099; }","duration":"534.72146ms","start":"2026-04-27T19:43:12.802477Z","end":"2026-04-27T19:43:13.337199Z","steps":["trace[1209677544] 'read index received' (duration: 337.930747ms)","trace[1209677544] 'applied index is now lower than readState.Index' (duration: 196.789793ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:43:13.337279Z","caller":"traceutil/trace.go:171","msg":"trace[798882679] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"462.117479ms","start":"2026-04-27T19:43:12.875146Z","end":"2026-04-27T19:43:13.337264Z","steps":["trace[798882679] 'process raft request' (duration: 461.652058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:13.337298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.803562ms","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-04-27T19:43:13.337365Z","caller":"traceutil/trace.go:171","msg":"trace[1521100962] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1058; }","duration":"534.923035ms","start":"2026-04-27T19:43:12.802425Z","end":"2026-04-27T19:43:13.337348Z","steps":["trace[1521100962] 'agreement among raft nodes before linearized reading' (duration: 534.810672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:13.337416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:12.802405Z","time spent":"534.993656ms","remote":"127.0.0.1:59548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:43:13.337324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:12.875111Z","time spent":"462.191881ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:43:15.307707Z","caller":"traceutil/trace.go:171","msg":"trace[2095592681] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"127.78101ms","start":"2026-04-27T19:43:15.179893Z","end":"2026-04-27T19:43:15.307674Z","steps":["trace[2095592681] 'read index received' (duration: 36.001733ms)","trace[2095592681] 'applied index is now lower than readState.Index' (duration: 91.777637ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:43:15.307841Z","caller":"traceutil/trace.go:171","msg":"trace[1262492301] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"234.129689ms","start":"2026-04-27T19:43:15.073685Z","end":"2026-04-27T19:43:15.307815Z","steps":["trace[1262492301] 'process raft request' (duration: 141.560574ms)","trace[1262492301] 'compare' (duration: 92.131705ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:43:15.307888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.976014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:43:15.307943Z","caller":"traceutil/trace.go:171","msg":"trace[3228108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"128.088718ms","start":"2026-04-27T19:43:15.17984Z","end":"2026-04-27T19:43:15.307929Z","steps":["trace[3228108] 'agreement among raft nodes before linearized reading' (duration: 127.984775ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:15.465098Z","caller":"traceutil/trace.go:171","msg":"trace[1476425781] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"121.920658ms","start":"2026-04-27T19:43:15.343146Z","end":"2026-04-27T19:43:15.465067Z","steps":["trace[1476425781] 'process raft request' (duration: 117.099241ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:15.482064Z","caller":"traceutil/trace.go:171","msg":"trace[608394786] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"136.585363ms","start":"2026-04-27T19:43:15.345454Z","end":"2026-04-27T19:43:15.48204Z","steps":["trace[608394786] 'process raft request' (duration: 136.390628ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:15.48236Z","caller":"traceutil/trace.go:171","msg":"trace[1272436556] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1109; }","duration":"126.006386ms","start":"2026-04-27T19:43:15.356329Z","end":"2026-04-27T19:43:15.482336Z","steps":["trace[1272436556] 'read index received' (duration: 103.93108ms)","trace[1272436556] 'applied index is now lower than readState.Index' (duration: 22.074016ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:43:15.48245Z","caller":"traceutil/trace.go:171","msg":"trace[1501418545] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"136.517281ms","start":"2026-04-27T19:43:15.345912Z","end":"2026-04-27T19:43:15.482429Z","steps":["trace[1501418545] 'process raft request' (duration: 136.075491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:15.482499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.13645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:43:15.482466Z","caller":"traceutil/trace.go:171","msg":"trace[1758038726] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"136.152892ms","start":"2026-04-27T19:43:15.346272Z","end":"2026-04-27T19:43:15.482425Z","steps":["trace[1758038726] 'process raft request' (duration: 135.970238ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:15.482543Z","caller":"traceutil/trace.go:171","msg":"trace[2030469014] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1066; }","duration":"126.238022ms","start":"2026-04-27T19:43:15.356293Z","end":"2026-04-27T19:43:15.482531Z","steps":["trace[2030469014] 'agreement among raft nodes before linearized reading' (duration: 126.139389ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:17.156999Z","caller":"traceutil/trace.go:171","msg":"trace[1740570931] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1113; }","duration":"237.738737ms","start":"2026-04-27T19:43:16.919237Z","end":"2026-04-27T19:43:17.156976Z","steps":["trace[1740570931] 'read index received' (duration: 237.609764ms)","trace[1740570931] 'applied index is now lower than readState.Index' (duration: 128.053µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:43:17.157043Z","caller":"traceutil/trace.go:171","msg":"trace[1919714473] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"405.67544ms","start":"2026-04-27T19:43:16.751337Z","end":"2026-04-27T19:43:17.157012Z","steps":["trace[1919714473] 'process raft request' (duration: 405.501176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:17.157111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.86116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:43:17.157144Z","caller":"traceutil/trace.go:171","msg":"trace[1999070163] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1067; }","duration":"237.945582ms","start":"2026-04-27T19:43:16.919188Z","end":"2026-04-27T19:43:17.157133Z","steps":["trace[1999070163] 'agreement among raft nodes before linearized reading' (duration: 237.87551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:17.157176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:16.751317Z","time spent":"405.787523ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:43:17.388598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.456318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:43:17.388715Z","caller":"traceutil/trace.go:171","msg":"trace[782531629] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1067; }","duration":"160.635903ms","start":"2026-04-27T19:43:17.228054Z","end":"2026-04-27T19:43:17.38869Z","steps":["trace[782531629] 'count revisions from in-memory index tree' (duration: 160.359567ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:17.506559Z","caller":"traceutil/trace.go:171","msg":"trace[1304669819] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"113.330021ms","start":"2026-04-27T19:43:17.393205Z","end":"2026-04-27T19:43:17.506535Z","steps":["trace[1304669819] 'process raft request' (duration: 113.196168ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:17.603697Z","caller":"traceutil/trace.go:171","msg":"trace[33674480] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1114; }","duration":"117.673786ms","start":"2026-04-27T19:43:17.485996Z","end":"2026-04-27T19:43:17.603669Z","steps":["trace[33674480] 'read index received' (duration: 20.457489ms)","trace[33674480] 'applied index is now lower than readState.Index' (duration: 97.215106ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:43:17.603839Z","caller":"traceutil/trace.go:171","msg":"trace[1721422964] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"132.423164ms","start":"2026-04-27T19:43:17.471402Z","end":"2026-04-27T19:43:17.603825Z","steps":["trace[1721422964] 'process raft request' (duration: 123.989089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:17.604112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.097237ms","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-04-27T19:43:17.604162Z","caller":"traceutil/trace.go:171","msg":"trace[59070091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1069; }","duration":"118.187879ms","start":"2026-04-27T19:43:17.485961Z","end":"2026-04-27T19:43:17.604149Z","steps":["trace[59070091] 'agreement among raft nodes before linearized reading' (duration: 117.997983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:17.604343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.272186ms","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-04-27T19:43:17.604383Z","caller":"traceutil/trace.go:171","msg":"trace[824775624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1069; }","duration":"117.328107ms","start":"2026-04-27T19:43:17.487039Z","end":"2026-04-27T19:43:17.604367Z","steps":["trace[824775624] 'agreement among raft nodes before linearized reading' (duration: 117.232525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:17.604368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.87202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T19:43:17.604432Z","caller":"traceutil/trace.go:171","msg":"trace[883827863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1069; }","duration":"117.988813ms","start":"2026-04-27T19:43:17.486427Z","end":"2026-04-27T19:43:17.604416Z","steps":["trace[883827863] 'agreement among raft nodes before linearized reading' (duration: 117.781018ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:25.415675Z","caller":"traceutil/trace.go:171","msg":"trace[1805439508] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"223.576585ms","start":"2026-04-27T19:43:25.192072Z","end":"2026-04-27T19:43:25.415648Z","steps":["trace[1805439508] 'process raft request' (duration: 223.392601ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:29.704228Z","caller":"traceutil/trace.go:171","msg":"trace[1449474409] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"264.694416ms","start":"2026-04-27T19:43:29.4395Z","end":"2026-04-27T19:43:29.704195Z","steps":["trace[1449474409] 'process raft request' (duration: 264.528292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:30.334118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.101983ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T19:43:30.334397Z","caller":"traceutil/trace.go:171","msg":"trace[1382205597] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"544.487841ms","start":"2026-04-27T19:43:29.789892Z","end":"2026-04-27T19:43:30.334379Z","steps":["trace[1382205597] 'process raft request' (duration: 544.433699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:30.334503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:29.789871Z","time spent":"544.568603ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:43:30.334571Z","caller":"traceutil/trace.go:171","msg":"trace[1348366791] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"685.465904ms","start":"2026-04-27T19:43:29.649079Z","end":"2026-04-27T19:43:30.334545Z","steps":["trace[1348366791] 'process raft request' (duration: 685.124455ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:30.334603Z","caller":"traceutil/trace.go:171","msg":"trace[445297892] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1207; }","duration":"784.648879ms","start":"2026-04-27T19:43:29.549915Z","end":"2026-04-27T19:43:30.334564Z","steps":["trace[445297892] 'read index received' (duration: 154.041442ms)","trace[445297892] 'applied index is now lower than readState.Index' (duration: 630.602887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:43:30.334648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:29.649058Z","time spent":"685.545156ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:43:30.334894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.947686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-27T19:43:30.334954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.490536ms","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-04-27T19:43:30.334971Z","caller":"traceutil/trace.go:171","msg":"trace[971089963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1159; }","duration":"785.040448ms","start":"2026-04-27T19:43:29.54991Z","end":"2026-04-27T19:43:30.33495Z","steps":["trace[971089963] 'agreement among raft nodes before linearized reading' (duration: 784.763802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:30.334954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.36568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T19:43:30.334994Z","caller":"traceutil/trace.go:171","msg":"trace[1103035191] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1159; }","duration":"481.568628ms","start":"2026-04-27T19:43:29.853413Z","end":"2026-04-27T19:43:30.334982Z","steps":["trace[1103035191] 'agreement among raft nodes before linearized reading' (duration: 481.360573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:30.334999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.917378ms","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-04-27T19:43:30.335012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.459012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T19:43:30.335016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:29.549857Z","time spent":"785.150601ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:43:30.335033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:29.853392Z","time spent":"481.631069ms","remote":"127.0.0.1:39220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":100,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:43:30.335038Z","caller":"traceutil/trace.go:171","msg":"trace[1623070581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1159; }","duration":"600.521974ms","start":"2026-04-27T19:43:29.734507Z","end":"2026-04-27T19:43:30.335029Z","steps":["trace[1623070581] 'agreement among raft nodes before linearized reading' (duration: 600.397641ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:30.335031Z","caller":"traceutil/trace.go:171","msg":"trace[2020854050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"641.492043ms","start":"2026-04-27T19:43:29.693521Z","end":"2026-04-27T19:43:30.335013Z","steps":["trace[2020854050] 'agreement among raft nodes before linearized reading' (duration: 641.218966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:30.335071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:29.73449Z","time spent":"600.574925ms","remote":"127.0.0.1:38960","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-04-27T19:43:30.335038Z","caller":"traceutil/trace.go:171","msg":"trace[1298389896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1159; }","duration":"617.97889ms","start":"2026-04-27T19:43:29.717049Z","end":"2026-04-27T19:43:30.335027Z","steps":["trace[1298389896] 'agreement among raft nodes before linearized reading' (duration: 617.899048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:30.33508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:29.693504Z","time spent":"641.567124ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:43:30.335097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:29.717023Z","time spent":"618.067903ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T19:43:30.334919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.113772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:43:30.335171Z","caller":"traceutil/trace.go:171","msg":"trace[897529466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1159; }","duration":"156.412719ms","start":"2026-04-27T19:43:30.178751Z","end":"2026-04-27T19:43:30.335164Z","steps":["trace[897529466] 'agreement among raft nodes before linearized reading' (duration: 156.106292ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:43.981631Z","caller":"traceutil/trace.go:171","msg":"trace[102119720] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"170.114442ms","start":"2026-04-27T19:43:43.811494Z","end":"2026-04-27T19:43:43.981609Z","steps":["trace[102119720] 'process raft request' (duration: 170.00249ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:47.520523Z","caller":"traceutil/trace.go:171","msg":"trace[1823638679] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"168.865703ms","start":"2026-04-27T19:43:47.351638Z","end":"2026-04-27T19:43:47.520504Z","steps":["trace[1823638679] 'process raft request' (duration: 168.74495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:48.194964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.155115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:43:48.195055Z","caller":"traceutil/trace.go:171","msg":"trace[1952317039] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"358.86635ms","start":"2026-04-27T19:43:47.836173Z","end":"2026-04-27T19:43:48.19504Z","steps":["trace[1952317039] 'read index received' (duration: 100.353µs)","trace[1952317039] 'applied index is now lower than readState.Index' (duration: 358.765007ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:43:48.195103Z","caller":"traceutil/trace.go:171","msg":"trace[1522575367] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"663.488958ms","start":"2026-04-27T19:43:47.531576Z","end":"2026-04-27T19:43:48.195065Z","steps":["trace[1522575367] 'process raft request' (duration: 153.15929ms)","trace[1522575367] 'compare' (duration: 510.043831ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:43:48.195181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.016864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:43:48.195217Z","caller":"traceutil/trace.go:171","msg":"trace[1357017486] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1236; }","duration":"359.056285ms","start":"2026-04-27T19:43:47.836148Z","end":"2026-04-27T19:43:48.195204Z","steps":["trace[1357017486] 'agreement among raft nodes before linearized reading' (duration: 358.992444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:48.195239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:47.531563Z","time spent":"663.605401ms","remote":"127.0.0.1:39094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:43:48.195249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:47.836091Z","time spent":"359.149168ms","remote":"127.0.0.1:39020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:43:48.195291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.919194ms","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-04-27T19:43:48.195328Z","caller":"traceutil/trace.go:171","msg":"trace[1734848184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1236; }","duration":"198.981896ms","start":"2026-04-27T19:43:47.996334Z","end":"2026-04-27T19:43:48.195316Z","steps":["trace[1734848184] 'agreement among raft nodes before linearized reading' (duration: 198.869263ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:48.809281Z","caller":"traceutil/trace.go:171","msg":"trace[1423248507] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"219.368173ms","start":"2026-04-27T19:43:48.589886Z","end":"2026-04-27T19:43:48.809254Z","steps":["trace[1423248507] 'process raft request' (duration: 128.824827ms)","trace[1423248507] 'compare' (duration: 90.356182ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:43:48.809401Z","caller":"traceutil/trace.go:171","msg":"trace[452702284] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"217.798874ms","start":"2026-04-27T19:43:48.591577Z","end":"2026-04-27T19:43:48.809376Z","steps":["trace[452702284] 'process raft request' (duration: 217.61058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:49.394076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.78391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:43:49.39416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.909955ms","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-04-27T19:43:49.39419Z","caller":"traceutil/trace.go:171","msg":"trace[390782945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"214.941705ms","start":"2026-04-27T19:43:49.179223Z","end":"2026-04-27T19:43:49.394165Z","steps":["trace[390782945] 'range keys from in-memory index tree' (duration: 214.705169ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:49.394231Z","caller":"traceutil/trace.go:171","msg":"trace[2069418138] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1243; }","duration":"167.021748ms","start":"2026-04-27T19:43:49.227192Z","end":"2026-04-27T19:43:49.394214Z","steps":["trace[2069418138] 'count revisions from in-memory index tree' (duration: 166.848364ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:49.885559Z","caller":"traceutil/trace.go:171","msg":"trace[2101000700] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"357.005425ms","start":"2026-04-27T19:43:49.52852Z","end":"2026-04-27T19:43:49.885525Z","steps":["trace[2101000700] 'process raft request' (duration: 356.840651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:49.885705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:49.5285Z","time spent":"357.129408ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5639,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:43:50.314443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.204517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:43:50.314526Z","caller":"traceutil/trace.go:171","msg":"trace[1899334997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"136.325421ms","start":"2026-04-27T19:43:50.178182Z","end":"2026-04-27T19:43:50.314508Z","steps":["trace[1899334997] 'range keys from in-memory index tree' (duration: 136.142566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:50.314746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.793986ms","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-04-27T19:43:50.314795Z","caller":"traceutil/trace.go:171","msg":"trace[733522591] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1245; }","duration":"121.855337ms","start":"2026-04-27T19:43:50.192933Z","end":"2026-04-27T19:43:50.314788Z","steps":["trace[733522591] 'range keys from in-memory index tree' (duration: 121.763905ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:50.383255Z","caller":"traceutil/trace.go:171","msg":"trace[730953045] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"124.822531ms","start":"2026-04-27T19:43:50.258412Z","end":"2026-04-27T19:43:50.383234Z","steps":["trace[730953045] 'read index received' (duration: 124.711308ms)","trace[730953045] 'applied index is now lower than readState.Index' (duration: 110.233µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:43:50.383282Z","caller":"traceutil/trace.go:171","msg":"trace[954215931] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"140.117142ms","start":"2026-04-27T19:43:50.243139Z","end":"2026-04-27T19:43:50.383256Z","steps":["trace[954215931] 'process raft request' (duration: 139.984189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:50.383518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.095137ms","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-04-27T19:43:50.38356Z","caller":"traceutil/trace.go:171","msg":"trace[587442686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1246; }","duration":"125.175329ms","start":"2026-04-27T19:43:50.258373Z","end":"2026-04-27T19:43:50.383548Z","steps":["trace[587442686] 'agreement among raft nodes before linearized reading' (duration: 124.968384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:50.880333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.168156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:43:50.880695Z","caller":"traceutil/trace.go:171","msg":"trace[1772218637] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"363.83975ms","start":"2026-04-27T19:43:50.51683Z","end":"2026-04-27T19:43:50.88067Z","steps":["trace[1772218637] 'process raft request' (duration: 363.775639ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:50.880822Z","caller":"traceutil/trace.go:171","msg":"trace[836530118] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"494.897841ms","start":"2026-04-27T19:43:50.385902Z","end":"2026-04-27T19:43:50.8808Z","steps":["trace[836530118] 'process raft request' (duration: 494.549143ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:50.880839Z","caller":"traceutil/trace.go:171","msg":"trace[1124042176] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"493.455207ms","start":"2026-04-27T19:43:50.387363Z","end":"2026-04-27T19:43:50.880818Z","steps":["trace[1124042176] 'process raft request' (duration: 493.16342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:50.880938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:50.385864Z","time spent":"495.022255ms","remote":"127.0.0.1:38778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:43:50.880956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:50.516812Z","time spent":"364.018675ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:43:50.880974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.410889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:43:50.880978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:50.387345Z","time spent":"493.55071ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:43:50.880919Z","caller":"traceutil/trace.go:171","msg":"trace[1712022468] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"561.901784ms","start":"2026-04-27T19:43:50.318952Z","end":"2026-04-27T19:43:50.880854Z","steps":["trace[1712022468] 'process raft request' (duration: 301.136393ms)","trace[1712022468] 'compare' (duration: 259.778717ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:43:50.881028Z","caller":"traceutil/trace.go:171","msg":"trace[859297668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1250; }","duration":"565.485062ms","start":"2026-04-27T19:43:50.315526Z","end":"2026-04-27T19:43:50.881011Z","steps":["trace[859297668] 'agreement among raft nodes before linearized reading' (duration: 565.394349ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:50.880916Z","caller":"traceutil/trace.go:171","msg":"trace[349947420] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"497.433283ms","start":"2026-04-27T19:43:50.383316Z","end":"2026-04-27T19:43:50.880749Z","steps":["trace[349947420] 'read index received' (duration: 236.788256ms)","trace[349947420] 'applied index is now lower than readState.Index' (duration: 260.641927ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:43:50.881125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:50.315515Z","time spent":"565.598294ms","remote":"127.0.0.1:38692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:43:50.88123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:50.31894Z","time spent":"562.17664ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:43:51.229335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.822277ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:43:51.229462Z","caller":"traceutil/trace.go:171","msg":"trace[200179411] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1250; }","duration":"121.973001ms","start":"2026-04-27T19:43:51.107472Z","end":"2026-04-27T19:43:51.229445Z","steps":["trace[200179411] 'range keys from in-memory index tree' (duration: 121.805087ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:51.229941Z","caller":"traceutil/trace.go:171","msg":"trace[83873510] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1306; }","duration":"348.741433ms","start":"2026-04-27T19:43:50.881188Z","end":"2026-04-27T19:43:51.229929Z","steps":["trace[83873510] 'read index received' (duration: 293.726813ms)","trace[83873510] 'applied index is now lower than readState.Index' (duration: 55.01322ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:43:51.230087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.417567ms","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-04-27T19:43:51.230113Z","caller":"traceutil/trace.go:171","msg":"trace[1433974068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1251; }","duration":"640.491329ms","start":"2026-04-27T19:43:50.589614Z","end":"2026-04-27T19:43:51.230105Z","steps":["trace[1433974068] 'agreement among raft nodes before linearized reading' (duration: 640.372796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:51.23033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:50.589591Z","time spent":"640.543859ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:43:51.230467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.027975ms","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-04-27T19:43:51.230495Z","caller":"traceutil/trace.go:171","msg":"trace[1853591572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1251; }","duration":"681.094227ms","start":"2026-04-27T19:43:50.549394Z","end":"2026-04-27T19:43:51.230488Z","steps":["trace[1853591572] 'agreement among raft nodes before linearized reading' (duration: 681.022535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:51.230521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:50.549374Z","time spent":"681.136367ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T19:43:51.230503Z","caller":"traceutil/trace.go:171","msg":"trace[1803838940] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"578.876008ms","start":"2026-04-27T19:43:50.651594Z","end":"2026-04-27T19:43:51.23047Z","steps":["trace[1803838940] 'process raft request' (duration: 522.742552ms)","trace[1803838940] 'compare' (duration: 54.966558ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:43:51.230609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.74409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T19:43:51.230629Z","caller":"traceutil/trace.go:171","msg":"trace[662311274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1251; }","duration":"416.804001ms","start":"2026-04-27T19:43:50.813819Z","end":"2026-04-27T19:43:51.230623Z","steps":["trace[662311274] 'agreement among raft nodes before linearized reading' (duration: 416.74712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:51.230652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:50.813797Z","time spent":"416.849682ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:43:51.230699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.883666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:43:51.230366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.459316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T19:43:51.23081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:50.651568Z","time spent":"579.027581ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:43:51.230865Z","caller":"traceutil/trace.go:171","msg":"trace[1339515736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1251; }","duration":"620.180683ms","start":"2026-04-27T19:43:50.610621Z","end":"2026-04-27T19:43:51.230802Z","steps":["trace[1339515736] 'agreement among raft nodes before linearized reading' (duration: 619.317213ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:51.230854Z","caller":"traceutil/trace.go:171","msg":"trace[1812843258] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1251; }","duration":"698.087531ms","start":"2026-04-27T19:43:50.532693Z","end":"2026-04-27T19:43:51.23078Z","steps":["trace[1812843258] 'agreement among raft nodes before linearized reading' (duration: 697.752843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:51.231003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:50.532635Z","time spent":"698.353197ms","remote":"127.0.0.1:59492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:43:51.230928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:50.610593Z","time spent":"620.319887ms","remote":"127.0.0.1:38960","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-04-27T19:43:51.283363Z","caller":"traceutil/trace.go:171","msg":"trace[2008938068] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"394.863277ms","start":"2026-04-27T19:43:50.888463Z","end":"2026-04-27T19:43:51.283326Z","steps":["trace[2008938068] 'process raft request' (duration: 394.743574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:51.283629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:50.888442Z","time spent":"395.087142ms","remote":"127.0.0.1:38778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:43:51.284343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.690985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:43:51.284438Z","caller":"traceutil/trace.go:171","msg":"trace[1844196455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1252; }","duration":"103.829579ms","start":"2026-04-27T19:43:51.180586Z","end":"2026-04-27T19:43:51.284415Z","steps":["trace[1844196455] 'agreement among raft nodes before linearized reading' (duration: 103.638914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:51.284424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.108145ms","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-04-27T19:43:51.284526Z","caller":"traceutil/trace.go:171","msg":"trace[896151853] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1252; }","duration":"398.29875ms","start":"2026-04-27T19:43:50.886207Z","end":"2026-04-27T19:43:51.284505Z","steps":["trace[896151853] 'agreement among raft nodes before linearized reading' (duration: 397.960582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:51.284579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:50.886185Z","time spent":"398.384182ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-27T19:43:51.439401Z","caller":"traceutil/trace.go:171","msg":"trace[523520999] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"203.801683ms","start":"2026-04-27T19:43:51.235568Z","end":"2026-04-27T19:43:51.43937Z","steps":["trace[523520999] 'process raft request' (duration: 147.323287ms)","trace[523520999] 'compare' (duration: 56.276292ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:43:51.439589Z","caller":"traceutil/trace.go:171","msg":"trace[425618102] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"203.993148ms","start":"2026-04-27T19:43:51.235577Z","end":"2026-04-27T19:43:51.43957Z","steps":["trace[425618102] 'process raft request' (duration: 203.735222ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:51.439921Z","caller":"traceutil/trace.go:171","msg":"trace[945822085] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"203.206499ms","start":"2026-04-27T19:43:51.236707Z","end":"2026-04-27T19:43:51.439913Z","steps":["trace[945822085] 'process raft request' (duration: 202.79423ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:51.439944Z","caller":"traceutil/trace.go:171","msg":"trace[1747240758] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"200.636117ms","start":"2026-04-27T19:43:51.239288Z","end":"2026-04-27T19:43:51.439924Z","steps":["trace[1747240758] 'process raft request' (duration: 200.536754ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:43:51.4722Z","caller":"traceutil/trace.go:171","msg":"trace[1051394029] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"177.610355ms","start":"2026-04-27T19:43:51.294209Z","end":"2026-04-27T19:43:51.47182Z","steps":["trace[1051394029] 'process raft request' (duration: 177.458371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:01.420145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174728898249837636,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:44:01.921215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174728898249837636,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:44:01.946935Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.033335324s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:44:01.947418Z","caller":"traceutil/trace.go:171","msg":"trace[1840314022] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"1.03395239s","start":"2026-04-27T19:44:00.91344Z","end":"2026-04-27T19:44:01.947392Z","steps":["trace[1840314022] 'process raft request' (duration: 1.033705534s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:01.947651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:00.913411Z","time spent":"1.034065693s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:44:01.952564Z","caller":"traceutil/trace.go:171","msg":"trace[60819496] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1380; }","duration":"1.033506699s","start":"2026-04-27T19:44:00.919032Z","end":"2026-04-27T19:44:01.952539Z","steps":["trace[60819496] 'read index received' (duration: 1.028068977s)","trace[60819496] 'applied index is now lower than readState.Index' (duration: 5.436812ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:44:01.952712Z","caller":"traceutil/trace.go:171","msg":"trace[921448927] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"354.056463ms","start":"2026-04-27T19:44:01.598631Z","end":"2026-04-27T19:44:01.952687Z","steps":["trace[921448927] 'process raft request' (duration: 353.773746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:01.952877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.052606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-27T19:44:01.952827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.114356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:44:01.952931Z","caller":"traceutil/trace.go:171","msg":"trace[582629242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1324; }","duration":"474.141628ms","start":"2026-04-27T19:44:01.478778Z","end":"2026-04-27T19:44:01.95292Z","steps":["trace[582629242] 'agreement among raft nodes before linearized reading' (duration: 473.977784ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:01.952949Z","caller":"traceutil/trace.go:171","msg":"trace[1847343312] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1324; }","duration":"784.29266ms","start":"2026-04-27T19:44:01.168648Z","end":"2026-04-27T19:44:01.95294Z","steps":["trace[1847343312] 'agreement among raft nodes before linearized reading' (duration: 784.103766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:01.952962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:01.47876Z","time spent":"474.192909ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:44:01.952978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:01.168624Z","time spent":"784.345652ms","remote":"127.0.0.1:39010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:44:01.952996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.924514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T19:44:01.953071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.515178ms","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-04-27T19:44:01.953092Z","caller":"traceutil/trace.go:171","msg":"trace[40220699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1324; }","duration":"456.071738ms","start":"2026-04-27T19:44:01.496998Z","end":"2026-04-27T19:44:01.95307Z","steps":["trace[40220699] 'agreement among raft nodes before linearized reading' (duration: 455.843822ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:01.953112Z","caller":"traceutil/trace.go:171","msg":"trace[1609554270] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1324; }","duration":"286.59849ms","start":"2026-04-27T19:44:01.666497Z","end":"2026-04-27T19:44:01.953095Z","steps":["trace[1609554270] 'agreement among raft nodes before linearized reading' (duration: 286.551199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:01.953088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033996101s","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-04-27T19:44:01.953155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:01.496978Z","time spent":"456.164089ms","remote":"127.0.0.1:38960","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-04-27T19:44:01.953181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.663572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:44:01.95318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.095768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-27T19:44:01.953245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.160447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-27T19:44:01.953306Z","caller":"traceutil/trace.go:171","msg":"trace[655089694] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1324; }","duration":"467.26235ms","start":"2026-04-27T19:44:01.486034Z","end":"2026-04-27T19:44:01.953296Z","steps":["trace[655089694] 'agreement among raft nodes before linearized reading' (duration: 467.071235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:01.953343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:01.486012Z","time spent":"467.322221ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-27T19:44:01.95321Z","caller":"traceutil/trace.go:171","msg":"trace[2086602697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"773.784075ms","start":"2026-04-27T19:44:01.179419Z","end":"2026-04-27T19:44:01.953203Z","steps":["trace[2086602697] 'agreement among raft nodes before linearized reading' (duration: 773.692473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:01.953433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:01.179397Z","time spent":"774.023661ms","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T19:44:01.953262Z","caller":"traceutil/trace.go:171","msg":"trace[614797831] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1324; }","duration":"626.216531ms","start":"2026-04-27T19:44:01.327034Z","end":"2026-04-27T19:44:01.953251Z","steps":["trace[614797831] 'agreement among raft nodes before linearized reading' (duration: 626.016306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:01.9535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:01.327013Z","time spent":"626.477708ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-27T19:44:01.953201Z","caller":"traceutil/trace.go:171","msg":"trace[372175971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1324; }","duration":"1.034193616s","start":"2026-04-27T19:44:00.918988Z","end":"2026-04-27T19:44:01.953182Z","steps":["trace[372175971] 'agreement among raft nodes before linearized reading' (duration: 1.033715025s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:01.953582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:00.918976Z","time spent":"1.034599267s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T19:44:01.953021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.568985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T19:44:01.953649Z","caller":"traceutil/trace.go:171","msg":"trace[653766213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1324; }","duration":"456.224311ms","start":"2026-04-27T19:44:01.497416Z","end":"2026-04-27T19:44:01.953641Z","steps":["trace[653766213] 'agreement among raft nodes before linearized reading' (duration: 455.504594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:01.953671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:01.497405Z","time spent":"456.260642ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:44:01.953854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.759053ms","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-04-27T19:44:01.953909Z","caller":"traceutil/trace.go:171","msg":"trace[142817356] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1324; }","duration":"458.850315ms","start":"2026-04-27T19:44:01.495045Z","end":"2026-04-27T19:44:01.953895Z","steps":["trace[142817356] 'agreement among raft nodes before linearized reading' (duration: 457.709978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:01.953957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:01.495024Z","time spent":"458.918716ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T19:44:01.954103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:01.59859Z","time spent":"354.201237ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:44:14.156393Z","caller":"traceutil/trace.go:171","msg":"trace[185927607] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"150.518845ms","start":"2026-04-27T19:44:14.005856Z","end":"2026-04-27T19:44:14.156375Z","steps":["trace[185927607] 'process raft request' (duration: 150.007923ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:14.156377Z","caller":"traceutil/trace.go:171","msg":"trace[1497890462] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1439; }","duration":"135.90025ms","start":"2026-04-27T19:44:14.020446Z","end":"2026-04-27T19:44:14.156346Z","steps":["trace[1497890462] 'read index received' (duration: 135.374297ms)","trace[1497890462] 'applied index is now lower than readState.Index' (duration: 524.583µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:44:14.156645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.917167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-27T19:44:14.156646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.171476ms","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-04-27T19:44:14.156696Z","caller":"traceutil/trace.go:171","msg":"trace[1591654466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1380; }","duration":"133.02024ms","start":"2026-04-27T19:44:14.023659Z","end":"2026-04-27T19:44:14.156679Z","steps":["trace[1591654466] 'agreement among raft nodes before linearized reading' (duration: 132.875676ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:14.156707Z","caller":"traceutil/trace.go:171","msg":"trace[580274918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1380; }","duration":"136.273979ms","start":"2026-04-27T19:44:14.020416Z","end":"2026-04-27T19:44:14.15669Z","steps":["trace[580274918] 'agreement among raft nodes before linearized reading' (duration: 136.065984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:14.156662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.041627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-27T19:44:14.156775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.267748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T19:44:14.15683Z","caller":"traceutil/trace.go:171","msg":"trace[677725647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1380; }","duration":"129.34795ms","start":"2026-04-27T19:44:14.027467Z","end":"2026-04-27T19:44:14.156815Z","steps":["trace[677725647] 'agreement among raft nodes before linearized reading' (duration: 129.191366ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:14.156817Z","caller":"traceutil/trace.go:171","msg":"trace[474567701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1380; }","duration":"123.21308ms","start":"2026-04-27T19:44:14.033577Z","end":"2026-04-27T19:44:14.15679Z","steps":["trace[474567701] 'agreement among raft nodes before linearized reading' (duration: 122.984485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:14.156958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.444728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-27T19:44:14.157005Z","caller":"traceutil/trace.go:171","msg":"trace[1489166217] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1380; }","duration":"128.53276ms","start":"2026-04-27T19:44:14.028462Z","end":"2026-04-27T19:44:14.156995Z","steps":["trace[1489166217] 'agreement among raft nodes before linearized reading' (duration: 128.347236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:14.157346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.35354ms","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-04-27T19:44:14.157385Z","caller":"traceutil/trace.go:171","msg":"trace[1313420620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1380; }","duration":"129.435112ms","start":"2026-04-27T19:44:14.027937Z","end":"2026-04-27T19:44:14.157372Z","steps":["trace[1313420620] 'agreement among raft nodes before linearized reading' (duration: 129.303909ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:26.007721Z","caller":"traceutil/trace.go:171","msg":"trace[1808728178] linearizableReadLoop","detail":"{readStateIndex:1498; appliedIndex:1497; }","duration":"269.61329ms","start":"2026-04-27T19:44:25.738092Z","end":"2026-04-27T19:44:26.007706Z","steps":["trace[1808728178] 'read index received' (duration: 269.443086ms)","trace[1808728178] 'applied index is now lower than readState.Index' (duration: 169.544µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:44:26.007804Z","caller":"traceutil/trace.go:171","msg":"trace[796618000] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"721.303024ms","start":"2026-04-27T19:44:25.286493Z","end":"2026-04-27T19:44:26.007796Z","steps":["trace[796618000] 'process raft request' (duration: 721.091099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:26.007895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:25.286471Z","time spent":"721.359115ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:44:26.00796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.641456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:44:26.008008Z","caller":"traceutil/trace.go:171","msg":"trace[941203437] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1435; }","duration":"126.748119ms","start":"2026-04-27T19:44:25.881251Z","end":"2026-04-27T19:44:26.007999Z","steps":["trace[941203437] 'agreement among raft nodes before linearized reading' (duration: 126.653057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:26.008013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.918847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:44:26.008112Z","caller":"traceutil/trace.go:171","msg":"trace[325333458] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1435; }","duration":"270.055941ms","start":"2026-04-27T19:44:25.738049Z","end":"2026-04-27T19:44:26.008105Z","steps":["trace[325333458] 'agreement among raft nodes before linearized reading' (duration: 269.941838ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:28.859093Z","caller":"traceutil/trace.go:171","msg":"trace[1901601819] linearizableReadLoop","detail":"{readStateIndex:1798; appliedIndex:1797; }","duration":"100.73879ms","start":"2026-04-27T19:45:28.758321Z","end":"2026-04-27T19:45:28.85906Z","steps":["trace[1901601819] 'read index received' (duration: 100.444783ms)","trace[1901601819] 'applied index is now lower than readState.Index' (duration: 292.447µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:45:28.859208Z","caller":"traceutil/trace.go:171","msg":"trace[1784419225] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"126.503885ms","start":"2026-04-27T19:45:28.732669Z","end":"2026-04-27T19:45:28.859173Z","steps":["trace[1784419225] 'process raft request' (duration: 126.112306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:28.859321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.982565ms","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-04-27T19:45:28.86003Z","caller":"traceutil/trace.go:171","msg":"trace[345059761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1723; }","duration":"101.722424ms","start":"2026-04-27T19:45:28.758275Z","end":"2026-04-27T19:45:28.859998Z","steps":["trace[345059761] 'agreement among raft nodes before linearized reading' (duration: 100.902543ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:31.045371Z","caller":"traceutil/trace.go:171","msg":"trace[1596897346] transaction","detail":"{read_only:false; response_revision:1733; number_of_response:1; }","duration":"114.446893ms","start":"2026-04-27T19:45:30.930897Z","end":"2026-04-27T19:45:31.045344Z","steps":["trace[1596897346] 'process raft request' (duration: 43.910908ms)","trace[1596897346] 'compare' (duration: 70.235108ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:45:31.119904Z","caller":"traceutil/trace.go:171","msg":"trace[728641424] linearizableReadLoop","detail":"{readStateIndex:1810; appliedIndex:1808; }","duration":"159.432536ms","start":"2026-04-27T19:45:30.960454Z","end":"2026-04-27T19:45:31.119886Z","steps":["trace[728641424] 'read index received' (duration: 14.263506ms)","trace[728641424] 'applied index is now lower than readState.Index' (duration: 145.16828ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:45:31.119971Z","caller":"traceutil/trace.go:171","msg":"trace[751161334] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"188.031781ms","start":"2026-04-27T19:45:30.931904Z","end":"2026-04-27T19:45:31.119936Z","steps":["trace[751161334] 'process raft request' (duration: 187.874627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:31.120109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.636231ms","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-04-27T19:45:31.120151Z","caller":"traceutil/trace.go:171","msg":"trace[1244224710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1734; }","duration":"159.733293ms","start":"2026-04-27T19:45:30.960406Z","end":"2026-04-27T19:45:31.12014Z","steps":["trace[1244224710] 'agreement among raft nodes before linearized reading' (duration: 159.589879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:31.120245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.186414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-27T19:45:31.12034Z","caller":"traceutil/trace.go:171","msg":"trace[78694347] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1734; }","duration":"132.329747ms","start":"2026-04-27T19:45:30.987991Z","end":"2026-04-27T19:45:31.12032Z","steps":["trace[78694347] 'agreement among raft nodes before linearized reading' (duration: 132.078061ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:33.103258Z","caller":"traceutil/trace.go:171","msg":"trace[688975864] transaction","detail":"{read_only:false; response_revision:1742; number_of_response:1; }","duration":"103.385393ms","start":"2026-04-27T19:45:32.999817Z","end":"2026-04-27T19:45:33.103202Z","steps":["trace[688975864] 'process raft request' (duration: 25.90375ms)","trace[688975864] 'compare' (duration: 77.310579ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:45:33.331924Z","caller":"traceutil/trace.go:171","msg":"trace[1568799986] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"137.737248ms","start":"2026-04-27T19:45:33.194161Z","end":"2026-04-27T19:45:33.331899Z","steps":["trace[1568799986] 'process raft request' (duration: 137.585535ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:35.062428Z","caller":"traceutil/trace.go:171","msg":"trace[1305080520] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"114.747789ms","start":"2026-04-27T19:45:34.947656Z","end":"2026-04-27T19:45:35.062404Z","steps":["trace[1305080520] 'process raft request' (duration: 114.569795ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:37.585686Z","caller":"traceutil/trace.go:171","msg":"trace[1162922166] transaction","detail":"{read_only:false; response_revision:1765; number_of_response:1; }","duration":"178.693394ms","start":"2026-04-27T19:45:37.406969Z","end":"2026-04-27T19:45:37.585662Z","steps":["trace[1162922166] 'process raft request' (duration: 178.629652ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:37.585945Z","caller":"traceutil/trace.go:171","msg":"trace[851000697] transaction","detail":"{read_only:false; response_revision:1764; number_of_response:1; }","duration":"179.800651ms","start":"2026-04-27T19:45:37.406125Z","end":"2026-04-27T19:45:37.585926Z","steps":["trace[851000697] 'process raft request' (duration: 124.748474ms)","trace[851000697] 'compare' (duration: 54.32117ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:45:43.721438Z","caller":"traceutil/trace.go:171","msg":"trace[1686723373] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"104.227414ms","start":"2026-04-27T19:45:43.617166Z","end":"2026-04-27T19:45:43.721394Z","steps":["trace[1686723373] 'process raft request' (duration: 71.189181ms)","trace[1686723373] 'compare' (duration: 32.8994ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:45:49.514134Z","caller":"traceutil/trace.go:171","msg":"trace[1547291575] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"969.239015ms","start":"2026-04-27T19:45:48.544876Z","end":"2026-04-27T19:45:49.514115Z","steps":["trace[1547291575] 'process raft request' (duration: 969.112742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:49.514255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:48.544853Z","time spent":"969.336048ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:45:50.00963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.030965ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T19:45:50.009922Z","caller":"traceutil/trace.go:171","msg":"trace[1813330144] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"492.208453ms","start":"2026-04-27T19:45:49.517695Z","end":"2026-04-27T19:45:50.009903Z","steps":["trace[1813330144] 'process raft request' (duration: 492.05961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:50.010053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:49.517687Z","time spent":"492.291606ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:45:50.010307Z","caller":"traceutil/trace.go:171","msg":"trace[49763909] linearizableReadLoop","detail":"{readStateIndex:1893; appliedIndex:1892; }","duration":"493.62974ms","start":"2026-04-27T19:45:49.516663Z","end":"2026-04-27T19:45:50.010292Z","steps":["trace[49763909] 'read index received' (duration: 1.698432ms)","trace[49763909] 'applied index is now lower than readState.Index' (duration: 491.929958ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:45:50.010563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.900206ms","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-04-27T19:45:50.010639Z","caller":"traceutil/trace.go:171","msg":"trace[2104454489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1814; }","duration":"494.009459ms","start":"2026-04-27T19:45:49.516612Z","end":"2026-04-27T19:45:50.010622Z","steps":["trace[2104454489] 'agreement among raft nodes before linearized reading' (duration: 493.777553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:50.010668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.955327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-27T19:45:50.010682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:49.5166Z","time spent":"494.07281ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T19:45:50.010707Z","caller":"traceutil/trace.go:171","msg":"trace[631795596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1814; }","duration":"494.008608ms","start":"2026-04-27T19:45:49.516688Z","end":"2026-04-27T19:45:50.010697Z","steps":["trace[631795596] 'agreement among raft nodes before linearized reading' (duration: 493.873925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:50.010757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:49.516684Z","time spent":"494.048419ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:45:50.010948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.267709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-27T19:45:50.010977Z","caller":"traceutil/trace.go:171","msg":"trace[1348235767] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1814; }","duration":"411.32142ms","start":"2026-04-27T19:45:49.599646Z","end":"2026-04-27T19:45:50.010968Z","steps":["trace[1348235767] 'agreement among raft nodes before linearized reading' (duration: 411.234598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:50.011021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:49.599635Z","time spent":"411.379172ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-27T19:45:50.011148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.490638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:45:50.011227Z","caller":"traceutil/trace.go:171","msg":"trace[1287893822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1814; }","duration":"491.569749ms","start":"2026-04-27T19:45:49.519633Z","end":"2026-04-27T19:45:50.011203Z","steps":["trace[1287893822] 'agreement among raft nodes before linearized reading' (duration: 491.471917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:50.011283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:49.519628Z","time spent":"491.643921ms","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:45:50.011285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.541489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-27T19:45:50.011331Z","caller":"traceutil/trace.go:171","msg":"trace[1172621833] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1814; }","duration":"491.59713ms","start":"2026-04-27T19:45:49.519721Z","end":"2026-04-27T19:45:50.011318Z","steps":["trace[1172621833] 'agreement among raft nodes before linearized reading' (duration: 491.484898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:50.011361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:49.519716Z","time spent":"491.638321ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-27T19:45:50.011616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.757995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:45:50.011646Z","caller":"traceutil/trace.go:171","msg":"trace[1766842238] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1814; }","duration":"106.793066ms","start":"2026-04-27T19:45:49.904844Z","end":"2026-04-27T19:45:50.011637Z","steps":["trace[1766842238] 'agreement among raft nodes before linearized reading' (duration: 106.740835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:50.011793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.12992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-27T19:45:50.011841Z","caller":"traceutil/trace.go:171","msg":"trace[1485724900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1814; }","duration":"494.190412ms","start":"2026-04-27T19:45:49.517637Z","end":"2026-04-27T19:45:50.011828Z","steps":["trace[1485724900] 'agreement among raft nodes before linearized reading' (duration: 494.061109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:50.011878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:49.517632Z","time spent":"494.236913ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:45:50.012053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.582305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-27T19:45:50.0121Z","caller":"traceutil/trace.go:171","msg":"trace[592247606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1814; }","duration":"223.656038ms","start":"2026-04-27T19:45:49.788432Z","end":"2026-04-27T19:45:50.012088Z","steps":["trace[592247606] 'agreement among raft nodes before linearized reading' (duration: 223.530355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:50.011004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.46066ms","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-04-27T19:45:50.012252Z","caller":"traceutil/trace.go:171","msg":"trace[1444294326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1814; }","duration":"300.747381ms","start":"2026-04-27T19:45:49.711484Z","end":"2026-04-27T19:45:50.012232Z","steps":["trace[1444294326] 'agreement among raft nodes before linearized reading' (duration: 299.296776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:50.012285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:49.711472Z","time spent":"300.802212ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T19:45:56.317225Z","caller":"traceutil/trace.go:171","msg":"trace[1377389559] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"189.019915ms","start":"2026-04-27T19:45:56.128182Z","end":"2026-04-27T19:45:56.317202Z","steps":["trace[1377389559] 'process raft request' (duration: 188.943003ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:56.317434Z","caller":"traceutil/trace.go:171","msg":"trace[1684805307] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"188.896841ms","start":"2026-04-27T19:45:56.128517Z","end":"2026-04-27T19:45:56.317414Z","steps":["trace[1684805307] 'process raft request' (duration: 188.644985ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:56.317633Z","caller":"traceutil/trace.go:171","msg":"trace[1868993912] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1925; }","duration":"137.991014ms","start":"2026-04-27T19:45:56.179633Z","end":"2026-04-27T19:45:56.317624Z","steps":["trace[1868993912] 'read index received' (duration: 45.933877ms)","trace[1868993912] 'applied index is now lower than readState.Index' (duration: 92.056397ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:45:56.31773Z","caller":"traceutil/trace.go:171","msg":"trace[1970561111] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"189.128918ms","start":"2026-04-27T19:45:56.128592Z","end":"2026-04-27T19:45:56.317721Z","steps":["trace[1970561111] 'process raft request' (duration: 188.754009ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:56.317753Z","caller":"traceutil/trace.go:171","msg":"trace[50842564] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"191.126406ms","start":"2026-04-27T19:45:56.126604Z","end":"2026-04-27T19:45:56.31773Z","steps":["trace[50842564] 'process raft request' (duration: 98.933415ms)","trace[50842564] 'compare' (duration: 91.477014ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:45:56.317865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.21968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:45:56.317894Z","caller":"traceutil/trace.go:171","msg":"trace[2140894120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1848; }","duration":"138.261271ms","start":"2026-04-27T19:45:56.179625Z","end":"2026-04-27T19:45:56.317886Z","steps":["trace[2140894120] 'agreement among raft nodes before linearized reading' (duration: 138.204809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:17.150131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174728898249839139,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:46:17.618337Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.177526158s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:46:17.618658Z","caller":"traceutil/trace.go:171","msg":"trace[1386615873] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"1.177931498s","start":"2026-04-27T19:46:16.440708Z","end":"2026-04-27T19:46:17.618639Z","steps":["trace[1386615873] 'process raft request' (duration: 1.177780054s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:17.618794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:16.440694Z","time spent":"1.178005829s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:46:17.678869Z","caller":"traceutil/trace.go:171","msg":"trace[117967728] linearizableReadLoop","detail":"{readStateIndex:2026; appliedIndex:2023; }","duration":"1.029064077s","start":"2026-04-27T19:46:16.649785Z","end":"2026-04-27T19:46:17.678849Z","steps":["trace[117967728] 'read index received' (duration: 968.839023ms)","trace[117967728] 'applied index is now lower than readState.Index' (duration: 60.224124ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:17.679007Z","caller":"traceutil/trace.go:171","msg":"trace[2061122230] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"1.235565678s","start":"2026-04-27T19:46:16.443436Z","end":"2026-04-27T19:46:17.679001Z","steps":["trace[2061122230] 'process raft request' (duration: 1.235367344s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:17.678882Z","caller":"traceutil/trace.go:171","msg":"trace[2123579116] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"1.236615113s","start":"2026-04-27T19:46:16.442251Z","end":"2026-04-27T19:46:17.678866Z","steps":["trace[2123579116] 'process raft request' (duration: 1.236242644s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:17.679104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:16.442238Z","time spent":"1.236824458s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:46:17.679104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:16.443415Z","time spent":"1.235620489s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:46:17.67919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029385995s","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:525"} {"level":"info","ts":"2026-04-27T19:46:17.679408Z","caller":"traceutil/trace.go:171","msg":"trace[988527510] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1941; }","duration":"1.02962895s","start":"2026-04-27T19:46:16.649748Z","end":"2026-04-27T19:46:17.679377Z","steps":["trace[988527510] 'agreement among raft nodes before linearized reading' (duration: 1.029268632s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:17.678975Z","caller":"traceutil/trace.go:171","msg":"trace[417562904] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"1.235606649s","start":"2026-04-27T19:46:16.443358Z","end":"2026-04-27T19:46:17.678964Z","steps":["trace[417562904] 'process raft request' (duration: 1.235403584s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:17.67946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:16.649734Z","time spent":"1.029713714s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-27T19:46:17.679537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:16.443352Z","time spent":"1.236108652s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:46:17.679685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.963505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:17.67972Z","caller":"traceutil/trace.go:171","msg":"trace[1764264967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1941; }","duration":"500.021266ms","start":"2026-04-27T19:46:17.179683Z","end":"2026-04-27T19:46:17.679705Z","steps":["trace[1764264967] 'agreement among raft nodes before linearized reading' (duration: 499.970845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:17.679746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:17.179652Z","time spent":"500.089488ms","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:46:17.679738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016210565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:46:17.679818Z","caller":"traceutil/trace.go:171","msg":"trace[2023103778] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1941; }","duration":"1.016313018s","start":"2026-04-27T19:46:16.663481Z","end":"2026-04-27T19:46:17.679794Z","steps":["trace[2023103778] 'agreement among raft nodes before linearized reading' (duration: 1.016133754s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:17.679826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.254589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-04-27T19:46:17.679882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:16.663473Z","time spent":"1.01639303s","remote":"127.0.0.1:39038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:46:17.679935Z","caller":"traceutil/trace.go:171","msg":"trace[417770022] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1941; }","duration":"337.407963ms","start":"2026-04-27T19:46:17.342502Z","end":"2026-04-27T19:46:17.67991Z","steps":["trace[417770022] 'agreement among raft nodes before linearized reading' (duration: 337.129507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:17.679983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:17.342485Z","time spent":"337.488716ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-27T19:46:43.923078Z","caller":"traceutil/trace.go:171","msg":"trace[690719025] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"105.205807ms","start":"2026-04-27T19:46:43.817842Z","end":"2026-04-27T19:46:43.923048Z","steps":["trace[690719025] 'process raft request' (duration: 48.78899ms)","trace[690719025] 'compare' (duration: 56.287284ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:52.169883Z","caller":"traceutil/trace.go:171","msg":"trace[725408755] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2188; }","duration":"149.520877ms","start":"2026-04-27T19:46:52.020338Z","end":"2026-04-27T19:46:52.169859Z","steps":["trace[725408755] 'read index received' (duration: 89.76422ms)","trace[725408755] 'applied index is now lower than readState.Index' (duration: 59.755727ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:52.169915Z","caller":"traceutil/trace.go:171","msg":"trace[2026790271] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"163.583791ms","start":"2026-04-27T19:46:52.006297Z","end":"2026-04-27T19:46:52.169881Z","steps":["trace[2026790271] 'process raft request' (duration: 103.802502ms)","trace[2026790271] 'compare' (duration: 59.654786ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:46:52.170081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.722984ms","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-04-27T19:46:52.170163Z","caller":"traceutil/trace.go:171","msg":"trace[604439084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2097; }","duration":"149.835966ms","start":"2026-04-27T19:46:52.020308Z","end":"2026-04-27T19:46:52.170144Z","steps":["trace[604439084] 'agreement among raft nodes before linearized reading' (duration: 149.62409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:52.170083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.732694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-27T19:46:52.170275Z","caller":"traceutil/trace.go:171","msg":"trace[1698981430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2097; }","duration":"149.98306ms","start":"2026-04-27T19:46:52.020275Z","end":"2026-04-27T19:46:52.170258Z","steps":["trace[1698981430] 'agreement among raft nodes before linearized reading' (duration: 149.63591ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:52.276442Z","caller":"traceutil/trace.go:171","msg":"trace[664961297] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"102.243114ms","start":"2026-04-27T19:46:52.174181Z","end":"2026-04-27T19:46:52.276424Z","steps":["trace[664961297] 'process raft request' (duration: 100.798869ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:52.276667Z","caller":"traceutil/trace.go:171","msg":"trace[161491310] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"101.767423ms","start":"2026-04-27T19:46:52.174886Z","end":"2026-04-27T19:46:52.276654Z","steps":["trace[161491310] 'process raft request' (duration: 101.699392ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:14.265221Z","caller":"traceutil/trace.go:171","msg":"trace[613299904] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"145.671744ms","start":"2026-04-27T19:47:14.119518Z","end":"2026-04-27T19:47:14.26519Z","steps":["trace[613299904] 'process raft request' (duration: 98.319769ms)","trace[613299904] 'compare' (duration: 47.214022ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:14.55326Z","caller":"traceutil/trace.go:171","msg":"trace[1933121001] linearizableReadLoop","detail":"{readStateIndex:2319; appliedIndex:2318; }","duration":"144.505395ms","start":"2026-04-27T19:47:14.408731Z","end":"2026-04-27T19:47:14.553237Z","steps":["trace[1933121001] 'read index received' (duration: 144.27644ms)","trace[1933121001] 'applied index is now lower than readState.Index' (duration: 227.615µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:47:14.553561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.822813ms","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-04-27T19:47:14.553646Z","caller":"traceutil/trace.go:171","msg":"trace[168925039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2221; }","duration":"144.948627ms","start":"2026-04-27T19:47:14.408676Z","end":"2026-04-27T19:47:14.553625Z","steps":["trace[168925039] 'agreement among raft nodes before linearized reading' (duration: 144.721191ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:14.553324Z","caller":"traceutil/trace.go:171","msg":"trace[773149467] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"191.228576ms","start":"2026-04-27T19:47:14.362059Z","end":"2026-04-27T19:47:14.553288Z","steps":["trace[773149467] 'process raft request' (duration: 191.040341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:14.554134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.052359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-27T19:47:14.55441Z","caller":"traceutil/trace.go:171","msg":"trace[503349486] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2221; }","duration":"145.572381ms","start":"2026-04-27T19:47:14.408821Z","end":"2026-04-27T19:47:14.554393Z","steps":["trace[503349486] 'agreement among raft nodes before linearized reading' (duration: 144.764682ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:20.80537Z","caller":"traceutil/trace.go:171","msg":"trace[424201594] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"119.756791ms","start":"2026-04-27T19:47:20.685574Z","end":"2026-04-27T19:47:20.805331Z","steps":["trace[424201594] 'process raft request' (duration: 61.419508ms)","trace[424201594] 'compare' (duration: 58.101637ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:47:26.287716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.737841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:47:26.287787Z","caller":"traceutil/trace.go:171","msg":"trace[694989897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2282; }","duration":"108.857104ms","start":"2026-04-27T19:47:26.178914Z","end":"2026-04-27T19:47:26.287771Z","steps":["trace[694989897] 'range keys from in-memory index tree' (duration: 108.648779ms)"],"step_count":1}