{"level":"warn","ts":"2026-06-05T22:09:09.851899Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T22:09:09.852Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-06-05T22:09:09.852081Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T22:09:09.852099Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-06-05T22:09:09.852121Z","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-06-05T22:09:09.852926Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-06-05T22:09:09.853032Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-06-05T22:09:09.857848Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.578327ms"} {"level":"info","ts":"2026-06-05T22:09:09.865798Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-06-05T22:09:09.8659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-06-05T22:09:09.866024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-06-05T22:09:09.866054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-05T22:09:09.866067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-06-05T22:09:09.86613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-06-05T22:09:09.87461Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-05T22:09:09.876145Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-05T22:09:09.877436Z","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-06-05T22:09:09.879812Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-05T22:09:09.879889Z","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-06-05T22:09:09.879941Z","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-06-05T22:09:09.879951Z","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-06-05T22:09:09.880182Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-05T22:09:09.883003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-06-05T22:09:09.883236Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-06-05T22:09:09.88593Z","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-06-05T22:09:09.886132Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-06-05T22:09:09.886173Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-06-05T22:09:09.886264Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-05T22:09:09.886323Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-05T22:09:10.867072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-06-05T22:09:10.867131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-05T22:09:10.867254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-06-05T22:09:10.867282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-06-05T22:09:10.867291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-06-05T22:09:10.867307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-06-05T22:09:10.867324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-06-05T22:09:10.867978Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T22:09:10.868713Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-06-05T22:09:10.868815Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T22:09:10.868893Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T22:09:10.869355Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T22:09:10.870413Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-05T22:09:10.870457Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-05T22:09:10.870571Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T22:09:10.870605Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T22:09:10.870656Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-06-05T22:09:10.871577Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-05T22:09:30.488788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.162088ms","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-06-05T22:09:30.488788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.61675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-05T22:09:30.488904Z","caller":"traceutil/trace.go:171","msg":"trace[2078381885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:423; }","duration":"117.322722ms","start":"2026-06-05T22:09:30.371563Z","end":"2026-06-05T22:09:30.488885Z","steps":["trace[2078381885] 'range keys from in-memory index tree' (duration: 117.090516ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:09:30.48891Z","caller":"traceutil/trace.go:171","msg":"trace[1487080132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"283.792194ms","start":"2026-06-05T22:09:30.205101Z","end":"2026-06-05T22:09:30.488893Z","steps":["trace[1487080132] 'range keys from in-memory index tree' (duration: 283.538298ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:09:47.374016Z","caller":"traceutil/trace.go:171","msg":"trace[1536337437] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"107.518455ms","start":"2026-06-05T22:09:47.266473Z","end":"2026-06-05T22:09:47.373992Z","steps":["trace[1536337437] 'process raft request' (duration: 106.738986ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:09:47.934494Z","caller":"traceutil/trace.go:171","msg":"trace[1947396556] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"401.185267ms","start":"2026-06-05T22:09:47.533288Z","end":"2026-06-05T22:09:47.934473Z","steps":["trace[1947396556] 'read index received' (duration: 401.153936ms)","trace[1947396556] 'applied index is now lower than readState.Index' (duration: 30.361µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:09:47.934582Z","caller":"traceutil/trace.go:171","msg":"trace[1763893643] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"402.352136ms","start":"2026-06-05T22:09:47.532194Z","end":"2026-06-05T22:09:47.934546Z","steps":["trace[1763893643] 'process raft request' (duration: 402.168591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:09:47.934756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.443663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-06-05T22:09:47.934834Z","caller":"traceutil/trace.go:171","msg":"trace[1846531781] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:621; }","duration":"401.567137ms","start":"2026-06-05T22:09:47.533248Z","end":"2026-06-05T22:09:47.934815Z","steps":["trace[1846531781] 'agreement among raft nodes before linearized reading' (duration: 401.31392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:09:47.934887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:09:47.53323Z","time spent":"401.648028ms","remote":"127.0.0.1:40554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7731,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-06-05T22:09:47.935607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:09:47.532183Z","time spent":"402.638484ms","remote":"127.0.0.1:39778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4387,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:09:47.940778Z","caller":"traceutil/trace.go:171","msg":"trace[1546088971] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"361.694628ms","start":"2026-06-05T22:09:47.579055Z","end":"2026-06-05T22:09:47.94075Z","steps":["trace[1546088971] 'process raft request' (duration: 361.326329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:09:47.940943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:09:47.579023Z","time spent":"361.844052ms","remote":"127.0.0.1:40554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:09:47.941239Z","caller":"traceutil/trace.go:171","msg":"trace[1742121020] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"361.056972ms","start":"2026-06-05T22:09:47.58016Z","end":"2026-06-05T22:09:47.941217Z","steps":["trace[1742121020] 'process raft request' (duration: 360.545128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:09:47.941353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:09:47.580148Z","time spent":"361.147735ms","remote":"127.0.0.1:40554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T22:09:47.941477Z","caller":"traceutil/trace.go:171","msg":"trace[1143849881] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"157.930804ms","start":"2026-06-05T22:09:47.783533Z","end":"2026-06-05T22:09:47.941464Z","steps":["trace[1143849881] 'process raft request' (duration: 157.597145ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:09:52.601118Z","caller":"traceutil/trace.go:171","msg":"trace[341500502] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"138.894368ms","start":"2026-06-05T22:09:52.462198Z","end":"2026-06-05T22:09:52.601092Z","steps":["trace[341500502] 'process raft request' (duration: 138.392965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:09:55.18193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.310314ms","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-06-05T22:09:55.181962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.061076ms","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-06-05T22:09:55.182002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.637159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wk5gs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-05T22:09:55.182029Z","caller":"traceutil/trace.go:171","msg":"trace[1312153816] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:692; }","duration":"353.457558ms","start":"2026-06-05T22:09:54.828554Z","end":"2026-06-05T22:09:55.182012Z","steps":["trace[1312153816] 'range keys from in-memory index tree' (duration: 353.125339ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:09:55.18205Z","caller":"traceutil/trace.go:171","msg":"trace[136230462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:692; }","duration":"553.211269ms","start":"2026-06-05T22:09:54.628816Z","end":"2026-06-05T22:09:55.182027Z","steps":["trace[136230462] 'range keys from in-memory index tree' (duration: 552.8247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:09:55.182083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:09:54.828534Z","time spent":"353.525779ms","remote":"127.0.0.1:40748","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-06-05T22:09:55.182099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:09:54.628796Z","time spent":"553.294751ms","remote":"127.0.0.1:40748","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-06-05T22:09:55.182133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.422797ms","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-06-05T22:09:55.182055Z","caller":"traceutil/trace.go:171","msg":"trace[1335853635] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wk5gs; range_end:; response_count:1; response_revision:692; }","duration":"672.726642ms","start":"2026-06-05T22:09:54.509313Z","end":"2026-06-05T22:09:55.182039Z","steps":["trace[1335853635] 'range keys from in-memory index tree' (duration: 672.467665ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:09:55.182171Z","caller":"traceutil/trace.go:171","msg":"trace[705938779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"566.495809ms","start":"2026-06-05T22:09:54.615665Z","end":"2026-06-05T22:09:55.182161Z","steps":["trace[705938779] 'range keys from in-memory index tree' (duration: 566.313444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:09:55.182201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:09:54.615645Z","time spent":"566.54854ms","remote":"127.0.0.1:40748","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-06-05T22:09:55.182233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:09:54.509293Z","time spent":"672.896707ms","remote":"127.0.0.1:40674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-wk5gs\" "} {"level":"info","ts":"2026-06-05T22:10:11.133354Z","caller":"traceutil/trace.go:171","msg":"trace[1639330898] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"531.848294ms","start":"2026-06-05T22:10:10.601482Z","end":"2026-06-05T22:10:11.13333Z","steps":["trace[1639330898] 'process raft request' (duration: 531.70991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:10:11.133587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:10:10.601466Z","time spent":"532.033728ms","remote":"127.0.0.1:40674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:10:45.576128Z","caller":"traceutil/trace.go:171","msg":"trace[1978019675] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"127.894976ms","start":"2026-06-05T22:10:45.448202Z","end":"2026-06-05T22:10:45.576097Z","steps":["trace[1978019675] 'process raft request' (duration: 127.728282ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:10:45.754715Z","caller":"traceutil/trace.go:171","msg":"trace[1932686726] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"304.791615ms","start":"2026-06-05T22:10:45.449892Z","end":"2026-06-05T22:10:45.754684Z","steps":["trace[1932686726] 'process raft request' (duration: 304.721433ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:10:45.754794Z","caller":"traceutil/trace.go:171","msg":"trace[454425943] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"306.256342ms","start":"2026-06-05T22:10:45.448499Z","end":"2026-06-05T22:10:45.754755Z","steps":["trace[454425943] 'process raft request' (duration: 305.939944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:10:45.754932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:10:45.449879Z","time spent":"304.930098ms","remote":"127.0.0.1:40748","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-06-05T22:10:45.754971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:10:45.448477Z","time spent":"306.401796ms","remote":"127.0.0.1:40748","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-06-05T22:11:06.780962Z","caller":"traceutil/trace.go:171","msg":"trace[272624615] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"109.636622ms","start":"2026-06-05T22:11:06.671301Z","end":"2026-06-05T22:11:06.780938Z","steps":["trace[272624615] 'read index received' (duration: 109.414517ms)","trace[272624615] 'applied index is now lower than readState.Index' (duration: 221.245µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:06.781072Z","caller":"traceutil/trace.go:171","msg":"trace[2009276268] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"175.974016ms","start":"2026-06-05T22:11:06.605069Z","end":"2026-06-05T22:11:06.781043Z","steps":["trace[2009276268] 'process raft request' (duration: 175.73757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:06.781167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.820677ms","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-06-05T22:11:06.781213Z","caller":"traceutil/trace.go:171","msg":"trace[1252933453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1092; }","duration":"109.956391ms","start":"2026-06-05T22:11:06.671243Z","end":"2026-06-05T22:11:06.7812Z","steps":["trace[1252933453] 'agreement among raft nodes before linearized reading' (duration: 109.784406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:07.211584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.078598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:07.211894Z","caller":"traceutil/trace.go:171","msg":"trace[728190115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"241.443218ms","start":"2026-06-05T22:11:06.970432Z","end":"2026-06-05T22:11:07.211876Z","steps":["trace[728190115] 'range keys from in-memory index tree' (duration: 240.990706ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:08.022974Z","caller":"traceutil/trace.go:171","msg":"trace[247344067] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"182.537112ms","start":"2026-06-05T22:11:07.840404Z","end":"2026-06-05T22:11:08.022941Z","steps":["trace[247344067] 'read index received' (duration: 135.261982ms)","trace[247344067] 'applied index is now lower than readState.Index' (duration: 47.27389ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:08.023165Z","caller":"traceutil/trace.go:171","msg":"trace[1889969122] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"196.103827ms","start":"2026-06-05T22:11:07.827016Z","end":"2026-06-05T22:11:08.02312Z","steps":["trace[1889969122] 'process raft request' (duration: 148.753185ms)","trace[1889969122] 'compare' (duration: 46.89836ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:08.023279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.866701ms","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-06-05T22:11:08.023341Z","caller":"traceutil/trace.go:171","msg":"trace[114977246] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1095; }","duration":"183.004144ms","start":"2026-06-05T22:11:07.84032Z","end":"2026-06-05T22:11:08.023324Z","steps":["trace[114977246] 'agreement among raft nodes before linearized reading' (duration: 182.799289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:08.023435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.67419ms","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-06-05T22:11:08.023511Z","caller":"traceutil/trace.go:171","msg":"trace[1200761602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1095; }","duration":"179.780272ms","start":"2026-06-05T22:11:07.843714Z","end":"2026-06-05T22:11:08.023495Z","steps":["trace[1200761602] 'agreement among raft nodes before linearized reading' (duration: 179.456294ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:08.238016Z","caller":"traceutil/trace.go:171","msg":"trace[904190188] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"210.299246ms","start":"2026-06-05T22:11:08.027525Z","end":"2026-06-05T22:11:08.237824Z","steps":["trace[904190188] 'process raft request' (duration: 181.776653ms)","trace[904190188] 'compare' (duration: 28.339809ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:08.270872Z","caller":"traceutil/trace.go:171","msg":"trace[1987277922] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"242.902285ms","start":"2026-06-05T22:11:08.027948Z","end":"2026-06-05T22:11:08.27085Z","steps":["trace[1987277922] 'process raft request' (duration: 242.761921ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:08.271007Z","caller":"traceutil/trace.go:171","msg":"trace[374144188] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"204.267953ms","start":"2026-06-05T22:11:08.066721Z","end":"2026-06-05T22:11:08.270989Z","steps":["trace[374144188] 'process raft request' (duration: 204.094619ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:08.27101Z","caller":"traceutil/trace.go:171","msg":"trace[1545717481] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1135; }","duration":"100.194903ms","start":"2026-06-05T22:11:08.170788Z","end":"2026-06-05T22:11:08.270983Z","steps":["trace[1545717481] 'read index received' (duration: 38.520398ms)","trace[1545717481] 'applied index is now lower than readState.Index' (duration: 61.672585ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:08.271152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.348967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:08.271182Z","caller":"traceutil/trace.go:171","msg":"trace[2001214228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"100.430939ms","start":"2026-06-05T22:11:08.170742Z","end":"2026-06-05T22:11:08.271173Z","steps":["trace[2001214228] 'agreement among raft nodes before linearized reading' (duration: 100.331646ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:08.909343Z","caller":"traceutil/trace.go:171","msg":"trace[465337916] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1138; }","duration":"112.970176ms","start":"2026-06-05T22:11:08.796349Z","end":"2026-06-05T22:11:08.909319Z","steps":["trace[465337916] 'read index received' (duration: 112.71981ms)","trace[465337916] 'applied index is now lower than readState.Index' (duration: 249.076µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:08.909423Z","caller":"traceutil/trace.go:171","msg":"trace[1858768436] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"119.276567ms","start":"2026-06-05T22:11:08.790122Z","end":"2026-06-05T22:11:08.909399Z","steps":["trace[1858768436] 'process raft request' (duration: 119.05112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:08.90996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.622844ms","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-06-05T22:11:08.910018Z","caller":"traceutil/trace.go:171","msg":"trace[1657473409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1099; }","duration":"113.755027ms","start":"2026-06-05T22:11:08.79625Z","end":"2026-06-05T22:11:08.910005Z","steps":["trace[1657473409] 'agreement among raft nodes before linearized reading' (duration: 113.196153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:24.04149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:23.668456Z","time spent":"373.029927ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-05T22:11:24.041692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.823168ms","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-06-05T22:11:24.041745Z","caller":"traceutil/trace.go:171","msg":"trace[208764599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1196; }","duration":"191.93795ms","start":"2026-06-05T22:11:23.849791Z","end":"2026-06-05T22:11:24.041729Z","steps":["trace[208764599] 'agreement among raft nodes before linearized reading' (duration: 191.755216ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:24.041475Z","caller":"traceutil/trace.go:171","msg":"trace[629556607] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1241; }","duration":"191.595302ms","start":"2026-06-05T22:11:23.849848Z","end":"2026-06-05T22:11:24.041443Z","steps":["trace[629556607] 'read index received' (duration: 191.424058ms)","trace[629556607] 'applied index is now lower than readState.Index' (duration: 169.824µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:24.672058Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960986905113682,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T22:11:24.784839Z","caller":"traceutil/trace.go:171","msg":"trace[1917791420] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"741.711533ms","start":"2026-06-05T22:11:24.043092Z","end":"2026-06-05T22:11:24.784804Z","steps":["trace[1917791420] 'process raft request' (duration: 737.064205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:24.785147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:24.043072Z","time spent":"741.860157ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:24.793516Z","caller":"traceutil/trace.go:171","msg":"trace[1559385947] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"746.641089ms","start":"2026-06-05T22:11:24.046859Z","end":"2026-06-05T22:11:24.7935Z","steps":["trace[1559385947] 'process raft request' (duration: 746.508896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:24.793741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:24.046846Z","time spent":"746.715901ms","remote":"127.0.0.1:40748","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-06-05T22:11:24.793742Z","caller":"traceutil/trace.go:171","msg":"trace[1045761867] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1242; }","duration":"622.733944ms","start":"2026-06-05T22:11:24.170969Z","end":"2026-06-05T22:11:24.793703Z","steps":["trace[1045761867] 'read index received' (duration: 609.311143ms)","trace[1045761867] 'applied index is now lower than readState.Index' (duration: 13.420401ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:24.79378Z","caller":"traceutil/trace.go:171","msg":"trace[290800145] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"719.264043ms","start":"2026-06-05T22:11:24.074484Z","end":"2026-06-05T22:11:24.793748Z","steps":["trace[290800145] 'process raft request' (duration: 718.975896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:24.793918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:24.07446Z","time spent":"719.387907ms","remote":"127.0.0.1:40748","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-06-05T22:11:24.793947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.96199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:24.793991Z","caller":"traceutil/trace.go:171","msg":"trace[1197920135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1199; }","duration":"623.021602ms","start":"2026-06-05T22:11:24.17095Z","end":"2026-06-05T22:11:24.793972Z","steps":["trace[1197920135] 'agreement among raft nodes before linearized reading' (duration: 622.922829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:24.794038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:24.17088Z","time spent":"623.149585ms","remote":"127.0.0.1:40486","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-06-05T22:11:24.794293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.476361ms","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-06-05T22:11:24.79437Z","caller":"traceutil/trace.go:171","msg":"trace[608125807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1199; }","duration":"487.607084ms","start":"2026-06-05T22:11:24.306747Z","end":"2026-06-05T22:11:24.794354Z","steps":["trace[608125807] 'agreement among raft nodes before linearized reading' (duration: 487.43406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:24.79441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:24.306711Z","time spent":"487.691937ms","remote":"127.0.0.1:40748","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-06-05T22:11:24.794554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.052607ms","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-06-05T22:11:24.794591Z","caller":"traceutil/trace.go:171","msg":"trace[1376806071] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1199; }","duration":"453.116739ms","start":"2026-06-05T22:11:24.341464Z","end":"2026-06-05T22:11:24.794581Z","steps":["trace[1376806071] 'agreement among raft nodes before linearized reading' (duration: 453.027857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:24.79462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:24.341448Z","time spent":"453.162661ms","remote":"127.0.0.1:40748","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-06-05T22:11:37.21387Z","caller":"traceutil/trace.go:171","msg":"trace[685307940] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"128.200452ms","start":"2026-06-05T22:11:37.085639Z","end":"2026-06-05T22:11:37.21384Z","steps":["trace[685307940] 'process raft request' (duration: 54.564054ms)","trace[685307940] 'compare' (duration: 73.516705ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:41.260996Z","caller":"traceutil/trace.go:171","msg":"trace[913596222] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"372.139324ms","start":"2026-06-05T22:11:40.888833Z","end":"2026-06-05T22:11:41.260972Z","steps":["trace[913596222] 'read index received' (duration: 372.020761ms)","trace[913596222] 'applied index is now lower than readState.Index' (duration: 117.583µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:41.261284Z","caller":"traceutil/trace.go:171","msg":"trace[1429002859] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"411.501813ms","start":"2026-06-05T22:11:40.84976Z","end":"2026-06-05T22:11:41.261261Z","steps":["trace[1429002859] 'process raft request' (duration: 411.098763ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:41.261378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.849735Z","time spent":"411.575085ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:41.261596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.76221ms","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-06-05T22:11:41.261623Z","caller":"traceutil/trace.go:171","msg":"trace[603156090] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"372.830282ms","start":"2026-06-05T22:11:40.888786Z","end":"2026-06-05T22:11:41.261616Z","steps":["trace[603156090] 'agreement among raft nodes before linearized reading' (duration: 372.7451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:41.261647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.888762Z","time spent":"372.880732ms","remote":"127.0.0.1:40748","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-06-05T22:11:41.261788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.574899ms","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-06-05T22:11:41.261872Z","caller":"traceutil/trace.go:171","msg":"trace[1297570959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1271; }","duration":"371.717423ms","start":"2026-06-05T22:11:40.890135Z","end":"2026-06-05T22:11:41.261853Z","steps":["trace[1297570959] 'agreement among raft nodes before linearized reading' (duration: 371.495037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:41.261935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.890114Z","time spent":"371.805104ms","remote":"127.0.0.1:40748","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-06-05T22:11:41.262212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.578457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-05T22:11:41.26225Z","caller":"traceutil/trace.go:171","msg":"trace[1786509173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1271; }","duration":"116.648129ms","start":"2026-06-05T22:11:41.145592Z","end":"2026-06-05T22:11:41.26224Z","steps":["trace[1786509173] 'agreement among raft nodes before linearized reading' (duration: 116.550837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:41.262293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.890727ms","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-06-05T22:11:41.262321Z","caller":"traceutil/trace.go:171","msg":"trace[1908792471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"370.02757ms","start":"2026-06-05T22:11:40.892286Z","end":"2026-06-05T22:11:41.262314Z","steps":["trace[1908792471] 'agreement among raft nodes before linearized reading' (duration: 369.920928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:41.26234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.892265Z","time spent":"370.070702ms","remote":"127.0.0.1:40748","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-06-05T22:11:42.049245Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960986905113848,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T22:11:42.550405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960986905113848,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T22:11:42.659258Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.394302154s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T22:11:42.659651Z","caller":"traceutil/trace.go:171","msg":"trace[1999031560] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"1.394741725s","start":"2026-06-05T22:11:41.26488Z","end":"2026-06-05T22:11:42.659621Z","steps":["trace[1999031560] 'process raft request' (duration: 1.394579481s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.659813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:41.264864Z","time spent":"1.394866218s","remote":"127.0.0.1:40748","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-06-05T22:11:42.681988Z","caller":"traceutil/trace.go:171","msg":"trace[1562855435] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"1.416355313s","start":"2026-06-05T22:11:41.265598Z","end":"2026-06-05T22:11:42.681954Z","steps":["trace[1562855435] 'process raft request' (duration: 1.416166869s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.682137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:41.265573Z","time spent":"1.416475556s","remote":"127.0.0.1:40748","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-06-05T22:11:42.682208Z","caller":"traceutil/trace.go:171","msg":"trace[2097102781] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"1.416510556s","start":"2026-06-05T22:11:41.26568Z","end":"2026-06-05T22:11:42.682191Z","steps":["trace[2097102781] 'process raft request' (duration: 1.416235039s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.682303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:41.265673Z","time spent":"1.416585079s","remote":"127.0.0.1:40748","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-06-05T22:11:42.682379Z","caller":"traceutil/trace.go:171","msg":"trace[136312475] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1321; }","duration":"1.134014628s","start":"2026-06-05T22:11:41.548353Z","end":"2026-06-05T22:11:42.682367Z","steps":["trace[136312475] 'read index received' (duration: 1.111770733s)","trace[136312475] 'applied index is now lower than readState.Index' (duration: 22.242965ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:42.682406Z","caller":"traceutil/trace.go:171","msg":"trace[2012543583] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"1.415132922s","start":"2026-06-05T22:11:41.26726Z","end":"2026-06-05T22:11:42.682393Z","steps":["trace[2012543583] 'process raft request' (duration: 1.41506112s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.682459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:41.267254Z","time spent":"1.415176573s","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:42.6825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134168962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T22:11:42.682446Z","caller":"traceutil/trace.go:171","msg":"trace[1412478285] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"1.415686906s","start":"2026-06-05T22:11:41.266722Z","end":"2026-06-05T22:11:42.682409Z","steps":["trace[1412478285] 'process raft request' (duration: 1.415403429s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.682577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.723756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T22:11:42.682609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.819277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:42.682616Z","caller":"traceutil/trace.go:171","msg":"trace[621900030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"511.790588ms","start":"2026-06-05T22:11:42.170816Z","end":"2026-06-05T22:11:42.682606Z","steps":["trace[621900030] 'agreement among raft nodes before linearized reading' (duration: 511.732706ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:42.682641Z","caller":"traceutil/trace.go:171","msg":"trace[411909700] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1276; }","duration":"375.891479ms","start":"2026-06-05T22:11:42.306738Z","end":"2026-06-05T22:11:42.682629Z","steps":["trace[411909700] 'agreement among raft nodes before linearized reading' (duration: 375.847318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.682657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:42.1708Z","time spent":"511.850149ms","remote":"127.0.0.1:40486","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-06-05T22:11:42.682672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:42.306702Z","time spent":"375.962281ms","remote":"127.0.0.1:40768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-06-05T22:11:42.682751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:41.266711Z","time spent":"1.415863381s","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:42.682534Z","caller":"traceutil/trace.go:171","msg":"trace[1395647789] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1276; }","duration":"1.134248474s","start":"2026-06-05T22:11:41.548276Z","end":"2026-06-05T22:11:42.682525Z","steps":["trace[1395647789] 'agreement among raft nodes before linearized reading' (duration: 1.134167322s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.682957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:41.548252Z","time spent":"1.134689716s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-06-05T22:11:43.443187Z","caller":"traceutil/trace.go:171","msg":"trace[1426723341] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"118.379244ms","start":"2026-06-05T22:11:43.324781Z","end":"2026-06-05T22:11:43.44316Z","steps":["trace[1426723341] 'read index received' (duration: 118.22703ms)","trace[1426723341] 'applied index is now lower than readState.Index' (duration: 151.143µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:43.443325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.527568ms","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-06-05T22:11:43.443365Z","caller":"traceutil/trace.go:171","msg":"trace[1820291204] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1277; }","duration":"118.63519ms","start":"2026-06-05T22:11:43.324707Z","end":"2026-06-05T22:11:43.443342Z","steps":["trace[1820291204] 'agreement among raft nodes before linearized reading' (duration: 118.546028ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:43.443577Z","caller":"traceutil/trace.go:171","msg":"trace[2110034460] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"177.793602ms","start":"2026-06-05T22:11:43.265646Z","end":"2026-06-05T22:11:43.44344Z","steps":["trace[2110034460] 'process raft request' (duration: 177.367341ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:43.928164Z","caller":"traceutil/trace.go:171","msg":"trace[1677394010] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"108.405352ms","start":"2026-06-05T22:11:43.819728Z","end":"2026-06-05T22:11:43.928133Z","steps":["trace[1677394010] 'process raft request' (duration: 108.231147ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:45.148793Z","caller":"traceutil/trace.go:171","msg":"trace[798281433] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"461.167952ms","start":"2026-06-05T22:11:44.687595Z","end":"2026-06-05T22:11:45.148763Z","steps":["trace[798281433] 'read index received' (duration: 461.008608ms)","trace[798281433] 'applied index is now lower than readState.Index' (duration: 157.514µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:45.148923Z","caller":"traceutil/trace.go:171","msg":"trace[2023226496] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"477.673701ms","start":"2026-06-05T22:11:44.671206Z","end":"2026-06-05T22:11:45.14888Z","steps":["trace[2023226496] 'process raft request' (duration: 477.394594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:45.149144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:44.671172Z","time spent":"477.860236ms","remote":"127.0.0.1:40748","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-06-05T22:11:45.149214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.746522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-06-05T22:11:45.149199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.569262ms","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-06-05T22:11:45.149415Z","caller":"traceutil/trace.go:171","msg":"trace[1794663366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1280; }","duration":"461.809778ms","start":"2026-06-05T22:11:44.687561Z","end":"2026-06-05T22:11:45.149371Z","steps":["trace[1794663366] 'agreement among raft nodes before linearized reading' (duration: 461.309766ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:45.149271Z","caller":"traceutil/trace.go:171","msg":"trace[1637854696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1280; }","duration":"460.815713ms","start":"2026-06-05T22:11:44.688426Z","end":"2026-06-05T22:11:45.149242Z","steps":["trace[1637854696] 'agreement among raft nodes before linearized reading' (duration: 460.631559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:45.149515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:44.687544Z","time spent":"461.957312ms","remote":"127.0.0.1:40748","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-06-05T22:11:45.149598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:44.688418Z","time spent":"461.127821ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T22:11:45.149847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.419122ms","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-06-05T22:11:45.149978Z","caller":"traceutil/trace.go:171","msg":"trace[1776518041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1280; }","duration":"460.625387ms","start":"2026-06-05T22:11:44.689338Z","end":"2026-06-05T22:11:45.149963Z","steps":["trace[1776518041] 'agreement among raft nodes before linearized reading' (duration: 459.93524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:45.150088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:44.689313Z","time spent":"460.760281ms","remote":"127.0.0.1:40748","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-06-05T22:11:45.150085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.33475ms","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-06-05T22:11:45.150249Z","caller":"traceutil/trace.go:171","msg":"trace[328856318] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1280; }","duration":"460.511925ms","start":"2026-06-05T22:11:44.689716Z","end":"2026-06-05T22:11:45.150227Z","steps":["trace[328856318] 'agreement among raft nodes before linearized reading' (duration: 459.350196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:45.150292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:44.689707Z","time spent":"460.576256ms","remote":"127.0.0.1:40748","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-06-05T22:11:45.763798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.146775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T22:11:45.763911Z","caller":"traceutil/trace.go:171","msg":"trace[233516222] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"609.273901ms","start":"2026-06-05T22:11:45.154615Z","end":"2026-06-05T22:11:45.763889Z","steps":["trace[233516222] 'process raft request' (duration: 104.788538ms)","trace[233516222] 'compare' (duration: 503.990611ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:45.763973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:45.154596Z","time spent":"609.347324ms","remote":"127.0.0.1:40748","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-06-05T22:11:45.963118Z","caller":"traceutil/trace.go:171","msg":"trace[416590138] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"513.885941ms","start":"2026-06-05T22:11:45.449206Z","end":"2026-06-05T22:11:45.963092Z","steps":["trace[416590138] 'process raft request' (duration: 513.858351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:45.96324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:45.449178Z","time spent":"513.998603ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:45.963322Z","caller":"traceutil/trace.go:171","msg":"trace[1155303901] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1330; }","duration":"793.770585ms","start":"2026-06-05T22:11:45.169542Z","end":"2026-06-05T22:11:45.963312Z","steps":["trace[1155303901] 'read index received' (duration: 89.956173ms)","trace[1155303901] 'applied index is now lower than readState.Index' (duration: 703.813462ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:45.96338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.843756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:45.963403Z","caller":"traceutil/trace.go:171","msg":"trace[610543983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"793.886578ms","start":"2026-06-05T22:11:45.169511Z","end":"2026-06-05T22:11:45.963397Z","steps":["trace[610543983] 'agreement among raft nodes before linearized reading' (duration: 793.835307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:45.963421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:45.169501Z","time spent":"793.915938ms","remote":"127.0.0.1:40486","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-06-05T22:11:45.963417Z","caller":"traceutil/trace.go:171","msg":"trace[1246564612] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"625.009032ms","start":"2026-06-05T22:11:45.33838Z","end":"2026-06-05T22:11:45.963389Z","steps":["trace[1246564612] 'process raft request' (duration: 624.660172ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:45.96345Z","caller":"traceutil/trace.go:171","msg":"trace[1128404437] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"807.014501ms","start":"2026-06-05T22:11:45.15632Z","end":"2026-06-05T22:11:45.963335Z","steps":["trace[1128404437] 'process raft request' (duration: 806.6166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:45.963584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:45.338354Z","time spent":"625.132725ms","remote":"127.0.0.1:40748","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-06-05T22:11:45.963615Z","caller":"traceutil/trace.go:171","msg":"trace[132353885] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"805.264186ms","start":"2026-06-05T22:11:45.158303Z","end":"2026-06-05T22:11:45.963567Z","steps":["trace[132353885] 'process raft request' (duration: 804.706361ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:45.963723Z","caller":"traceutil/trace.go:171","msg":"trace[608001093] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"807.686788ms","start":"2026-06-05T22:11:45.156027Z","end":"2026-06-05T22:11:45.963714Z","steps":["trace[608001093] 'process raft request' (duration: 715.114809ms)","trace[608001093] 'compare' (duration: 91.547332ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:45.963762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:45.156001Z","time spent":"807.742209ms","remote":"127.0.0.1:40748","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-06-05T22:11:45.963893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:45.158296Z","time spent":"805.455191ms","remote":"127.0.0.1:40748","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-06-05T22:11:45.964022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:45.156311Z","time spent":"807.281288ms","remote":"127.0.0.1:40748","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-06-05T22:11:46.216668Z","caller":"traceutil/trace.go:171","msg":"trace[1228305201] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"170.785874ms","start":"2026-06-05T22:11:46.045855Z","end":"2026-06-05T22:11:46.216641Z","steps":["trace[1228305201] 'process raft request' (duration: 170.654751ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.259766Z","caller":"traceutil/trace.go:171","msg":"trace[651754858] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"209.117507ms","start":"2026-06-05T22:11:46.050614Z","end":"2026-06-05T22:11:46.259731Z","steps":["trace[651754858] 'process raft request' (duration: 208.994654ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.259771Z","caller":"traceutil/trace.go:171","msg":"trace[365222484] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"212.055151ms","start":"2026-06-05T22:11:46.047693Z","end":"2026-06-05T22:11:46.259748Z","steps":["trace[365222484] 'process raft request' (duration: 211.716423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:46.935148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.277191ms","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-06-05T22:11:46.935223Z","caller":"traceutil/trace.go:171","msg":"trace[1746206615] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1292; }","duration":"555.409114ms","start":"2026-06-05T22:11:46.379797Z","end":"2026-06-05T22:11:46.935206Z","steps":["trace[1746206615] 'count revisions from in-memory index tree' (duration: 555.181309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:46.935253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:46.379773Z","time spent":"555.472456ms","remote":"127.0.0.1:40554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-06-05T22:11:47.52091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.324039ms","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-06-05T22:11:47.521001Z","caller":"traceutil/trace.go:171","msg":"trace[975548517] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1293; }","duration":"162.477013ms","start":"2026-06-05T22:11:47.358504Z","end":"2026-06-05T22:11:47.520981Z","steps":["trace[975548517] 'count revisions from in-memory index tree' (duration: 162.229428ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:48.057224Z","caller":"traceutil/trace.go:171","msg":"trace[632925614] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"155.576969ms","start":"2026-06-05T22:11:47.901626Z","end":"2026-06-05T22:11:48.057203Z","steps":["trace[632925614] 'read index received' (duration: 155.374234ms)","trace[632925614] 'applied index is now lower than readState.Index' (duration: 201.685µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:48.057537Z","caller":"traceutil/trace.go:171","msg":"trace[1350433314] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"285.785902ms","start":"2026-06-05T22:11:47.771722Z","end":"2026-06-05T22:11:48.057508Z","steps":["trace[1350433314] 'process raft request' (duration: 285.320371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:48.057596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.937757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:48.05791Z","caller":"traceutil/trace.go:171","msg":"trace[816477085] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1294; }","duration":"156.316907ms","start":"2026-06-05T22:11:47.901571Z","end":"2026-06-05T22:11:48.057888Z","steps":["trace[816477085] 'agreement among raft nodes before linearized reading' (duration: 155.950348ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:48.178669Z","caller":"traceutil/trace.go:171","msg":"trace[239157436] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"120.99067ms","start":"2026-06-05T22:11:48.057654Z","end":"2026-06-05T22:11:48.178645Z","steps":["trace[239157436] 'read index received' (duration: 74.162173ms)","trace[239157436] 'applied index is now lower than readState.Index' (duration: 46.827237ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:48.178793Z","caller":"traceutil/trace.go:171","msg":"trace[706914735] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"209.172777ms","start":"2026-06-05T22:11:47.969525Z","end":"2026-06-05T22:11:48.178698Z","steps":["trace[706914735] 'process raft request' (duration: 162.306419ms)","trace[706914735] 'compare' (duration: 46.695514ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:48.179075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.334551ms","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-06-05T22:11:48.179148Z","caller":"traceutil/trace.go:171","msg":"trace[1647422982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1295; }","duration":"209.608439ms","start":"2026-06-05T22:11:47.969523Z","end":"2026-06-05T22:11:48.179131Z","steps":["trace[1647422982] 'agreement among raft nodes before linearized reading' (duration: 209.182418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:48.179045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.480676ms","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-06-05T22:11:48.179213Z","caller":"traceutil/trace.go:171","msg":"trace[2089152808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1295; }","duration":"211.708783ms","start":"2026-06-05T22:11:47.967494Z","end":"2026-06-05T22:11:48.179203Z","steps":["trace[2089152808] 'agreement among raft nodes before linearized reading' (duration: 211.339173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:48.179275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.553198ms","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-06-05T22:11:48.179358Z","caller":"traceutil/trace.go:171","msg":"trace[37667694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1295; }","duration":"211.866137ms","start":"2026-06-05T22:11:47.967478Z","end":"2026-06-05T22:11:48.179344Z","steps":["trace[37667694] 'agreement among raft nodes before linearized reading' (duration: 211.371233ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:48.52125Z","caller":"traceutil/trace.go:171","msg":"trace[132543031] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"338.422759ms","start":"2026-06-05T22:11:48.182805Z","end":"2026-06-05T22:11:48.521227Z","steps":["trace[132543031] 'process raft request' (duration: 338.381218ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:48.521291Z","caller":"traceutil/trace.go:171","msg":"trace[1980992201] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"338.845299ms","start":"2026-06-05T22:11:48.182405Z","end":"2026-06-05T22:11:48.521251Z","steps":["trace[1980992201] 'process raft request' (duration: 338.677655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:48.52137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:48.182786Z","time spent":"338.520221ms","remote":"127.0.0.1:40748","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-06-05T22:11:48.521467Z","caller":"traceutil/trace.go:171","msg":"trace[1000115136] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"337.900466ms","start":"2026-06-05T22:11:48.183561Z","end":"2026-06-05T22:11:48.521462Z","steps":["trace[1000115136] 'process raft request' (duration: 337.645739ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:48.52145Z","caller":"traceutil/trace.go:171","msg":"trace[593713866] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1346; }","duration":"326.964328ms","start":"2026-06-05T22:11:48.194461Z","end":"2026-06-05T22:11:48.521425Z","steps":["trace[593713866] 'read index received' (duration: 326.623509ms)","trace[593713866] 'applied index is now lower than readState.Index' (duration: 338.879µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:48.521496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:48.18354Z","time spent":"337.938336ms","remote":"127.0.0.1:40748","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-06-05T22:11:48.521507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:48.182377Z","time spent":"338.997573ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:48.521549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.086521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:48.52157Z","caller":"traceutil/trace.go:171","msg":"trace[2000202652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"327.129942ms","start":"2026-06-05T22:11:48.194435Z","end":"2026-06-05T22:11:48.521565Z","steps":["trace[2000202652] 'agreement among raft nodes before linearized reading' (duration: 327.091281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:48.521584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:48.194425Z","time spent":"327.155903ms","remote":"127.0.0.1:40496","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-06-05T22:11:48.521628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.713833ms","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-06-05T22:11:48.521678Z","caller":"traceutil/trace.go:171","msg":"trace[612745342] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1299; }","duration":"120.802096ms","start":"2026-06-05T22:11:48.400861Z","end":"2026-06-05T22:11:48.521663Z","steps":["trace[612745342] 'agreement among raft nodes before linearized reading' (duration: 120.702633ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:50.64087Z","caller":"traceutil/trace.go:171","msg":"trace[1130532319] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"111.435728ms","start":"2026-06-05T22:11:50.529383Z","end":"2026-06-05T22:11:50.640819Z","steps":["trace[1130532319] 'process raft request' (duration: 111.067859ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:50.641044Z","caller":"traceutil/trace.go:171","msg":"trace[646166484] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"110.663518ms","start":"2026-06-05T22:11:50.530351Z","end":"2026-06-05T22:11:50.641014Z","steps":["trace[646166484] 'process raft request' (duration: 110.237088ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:54.855114Z","caller":"traceutil/trace.go:171","msg":"trace[677050399] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"134.885384ms","start":"2026-06-05T22:11:54.720182Z","end":"2026-06-05T22:11:54.855068Z","steps":["trace[677050399] 'process raft request' (duration: 134.73571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:56.667307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256044471s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T22:11:56.667605Z","caller":"traceutil/trace.go:171","msg":"trace[605267136] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1398; }","duration":"1.437120576s","start":"2026-06-05T22:11:55.230459Z","end":"2026-06-05T22:11:56.667579Z","steps":["trace[605267136] 'read index received' (duration: 180.695705ms)","trace[605267136] 'applied index is now lower than readState.Index' (duration: 1.256423591s)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:56.667716Z","caller":"traceutil/trace.go:171","msg":"trace[1465288962] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"1.804792757s","start":"2026-06-05T22:11:54.862903Z","end":"2026-06-05T22:11:56.667696Z","steps":["trace[1465288962] 'process raft request' (duration: 548.249413ms)","trace[1465288962] 'compare' (duration: 1.255746913s)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:56.667793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:54.862887Z","time spent":"1.804863679s","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T22:11:56.668053Z","caller":"traceutil/trace.go:171","msg":"trace[1065190006] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"1.802353665s","start":"2026-06-05T22:11:54.865659Z","end":"2026-06-05T22:11:56.668012Z","steps":["trace[1065190006] 'process raft request' (duration: 1.801793391s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:56.668237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:54.86565Z","time spent":"1.802483778s","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:56.668262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437785333s","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-06-05T22:11:56.668313Z","caller":"traceutil/trace.go:171","msg":"trace[167776657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1348; }","duration":"1.437886505s","start":"2026-06-05T22:11:55.23041Z","end":"2026-06-05T22:11:56.668297Z","steps":["trace[167776657] 'agreement among raft nodes before linearized reading' (duration: 1.437649799s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:56.668405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:55.230387Z","time spent":"1.437999208s","remote":"127.0.0.1:40748","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-06-05T22:11:56.673495Z","caller":"traceutil/trace.go:171","msg":"trace[1831000533] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"427.003727ms","start":"2026-06-05T22:11:56.246468Z","end":"2026-06-05T22:11:56.673472Z","steps":["trace[1831000533] 'process raft request' (duration: 426.669408ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:56.673968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.246438Z","time spent":"427.397247ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:56.718437Z","caller":"traceutil/trace.go:171","msg":"trace[2031472447] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"468.953282ms","start":"2026-06-05T22:11:56.249464Z","end":"2026-06-05T22:11:56.718418Z","steps":["trace[2031472447] 'process raft request' (duration: 468.828449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:56.718532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.249435Z","time spent":"469.050235ms","remote":"127.0.0.1:40748","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-06-05T22:11:56.718739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.390396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:56.718805Z","caller":"traceutil/trace.go:171","msg":"trace[1935564221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"547.500998ms","start":"2026-06-05T22:11:56.17128Z","end":"2026-06-05T22:11:56.718781Z","steps":["trace[1935564221] 'agreement among raft nodes before linearized reading' (duration: 547.420836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:56.718835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.171249Z","time spent":"547.579591ms","remote":"127.0.0.1:40486","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-06-05T22:11:56.718843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.106428ms","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-06-05T22:11:56.71891Z","caller":"traceutil/trace.go:171","msg":"trace[1457782030] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1350; }","duration":"388.17215ms","start":"2026-06-05T22:11:56.330703Z","end":"2026-06-05T22:11:56.718875Z","steps":["trace[1457782030] 'agreement among raft nodes before linearized reading' (duration: 388.071867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:56.718724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.054276ms","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":"warn","ts":"2026-06-05T22:11:56.718977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.330692Z","time spent":"388.260332ms","remote":"127.0.0.1:40748","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":"info","ts":"2026-06-05T22:11:56.719002Z","caller":"traceutil/trace.go:171","msg":"trace[744031848] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1350; }","duration":"444.395395ms","start":"2026-06-05T22:11:56.274596Z","end":"2026-06-05T22:11:56.718991Z","steps":["trace[744031848] 'agreement among raft nodes before linearized reading' (duration: 443.839881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:56.719071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.274581Z","time spent":"444.479106ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-05T22:11:56.71916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479255074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T22:11:56.719279Z","caller":"traceutil/trace.go:171","msg":"trace[1999397779] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1350; }","duration":"1.479415538s","start":"2026-06-05T22:11:55.239844Z","end":"2026-06-05T22:11:56.71926Z","steps":["trace[1999397779] 'agreement among raft nodes before linearized reading' (duration: 1.47906768s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:56.719458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:55.239824Z","time spent":"1.479611554s","remote":"127.0.0.1:40860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-06-05T22:11:56.719213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.53803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-05T22:11:56.719746Z","caller":"traceutil/trace.go:171","msg":"trace[1216275015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1350; }","duration":"612.108244ms","start":"2026-06-05T22:11:56.107623Z","end":"2026-06-05T22:11:56.719731Z","steps":["trace[1216275015] 'agreement among raft nodes before linearized reading' (duration: 611.521099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:56.719813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.10759Z","time spent":"612.209237ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-05T22:11:57.183539Z","caller":"traceutil/trace.go:171","msg":"trace[861744196] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"508.260691ms","start":"2026-06-05T22:11:56.675248Z","end":"2026-06-05T22:11:57.183509Z","steps":["trace[861744196] 'process raft request' (duration: 508.051645ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:57.183577Z","caller":"traceutil/trace.go:171","msg":"trace[755806273] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1403; }","duration":"465.148925ms","start":"2026-06-05T22:11:56.718387Z","end":"2026-06-05T22:11:57.183536Z","steps":["trace[755806273] 'read index received' (duration: 465.121885ms)","trace[755806273] 'applied index is now lower than readState.Index' (duration: 21.37µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:57.183679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.675226Z","time spent":"508.377074ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:57.183901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.440144ms","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-06-05T22:11:57.183946Z","caller":"traceutil/trace.go:171","msg":"trace[604203993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1351; }","duration":"473.537376ms","start":"2026-06-05T22:11:56.710399Z","end":"2026-06-05T22:11:57.183936Z","steps":["trace[604203993] 'agreement among raft nodes before linearized reading' (duration: 473.29449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:57.183983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.710387Z","time spent":"473.587548ms","remote":"127.0.0.1:40748","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-06-05T22:11:57.184264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.815332ms","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-06-05T22:11:57.184296Z","caller":"traceutil/trace.go:171","msg":"trace[158635195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1351; }","duration":"496.891654ms","start":"2026-06-05T22:11:56.687395Z","end":"2026-06-05T22:11:57.184287Z","steps":["trace[158635195] 'agreement among raft nodes before linearized reading' (duration: 496.801912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:57.184317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.687375Z","time spent":"496.937686ms","remote":"127.0.0.1:40748","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-06-05T22:11:57.186738Z","caller":"traceutil/trace.go:171","msg":"trace[1639632954] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"462.473468ms","start":"2026-06-05T22:11:56.724249Z","end":"2026-06-05T22:11:57.186722Z","steps":["trace[1639632954] 'process raft request' (duration: 462.352845ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:57.186759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.710309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T22:11:57.1868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.724238Z","time spent":"462.527369ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:57.186876Z","caller":"traceutil/trace.go:171","msg":"trace[1915712424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1355; }","duration":"465.863834ms","start":"2026-06-05T22:11:56.720992Z","end":"2026-06-05T22:11:57.186856Z","steps":["trace[1915712424] 'agreement among raft nodes before linearized reading' (duration: 465.667529ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:57.186922Z","caller":"traceutil/trace.go:171","msg":"trace[1591737380] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"463.012442ms","start":"2026-06-05T22:11:56.723901Z","end":"2026-06-05T22:11:57.186913Z","steps":["trace[1591737380] 'process raft request' (duration: 462.668084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:57.18693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.720979Z","time spent":"465.941945ms","remote":"127.0.0.1:40496","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-06-05T22:11:57.186959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.723893Z","time spent":"463.044793ms","remote":"127.0.0.1:40748","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-06-05T22:11:57.187169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.409469ms","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-06-05T22:11:57.187213Z","caller":"traceutil/trace.go:171","msg":"trace[264177399] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1355; }","duration":"141.45686ms","start":"2026-06-05T22:11:57.045743Z","end":"2026-06-05T22:11:57.1872Z","steps":["trace[264177399] 'agreement among raft nodes before linearized reading' (duration: 141.389049ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:57.187239Z","caller":"traceutil/trace.go:171","msg":"trace[1083107803] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"463.925204ms","start":"2026-06-05T22:11:56.723306Z","end":"2026-06-05T22:11:57.187231Z","steps":["trace[1083107803] 'process raft request' (duration: 463.080353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:57.187278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.723268Z","time spent":"463.989047ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:57.187504Z","caller":"traceutil/trace.go:171","msg":"trace[1184441546] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"464.140829ms","start":"2026-06-05T22:11:56.723345Z","end":"2026-06-05T22:11:57.187486Z","steps":["trace[1184441546] 'process raft request' (duration: 463.175585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:57.187553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:56.723324Z","time spent":"464.204841ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:59.346137Z","caller":"traceutil/trace.go:171","msg":"trace[1257242413] linearizableReadLoop","detail":"{readStateIndex:1421; appliedIndex:1418; }","duration":"148.077087ms","start":"2026-06-05T22:11:59.198031Z","end":"2026-06-05T22:11:59.346108Z","steps":["trace[1257242413] 'read index received' (duration: 42.253224ms)","trace[1257242413] 'applied index is now lower than readState.Index' (duration: 105.822903ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:59.346228Z","caller":"traceutil/trace.go:171","msg":"trace[306935411] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"151.121854ms","start":"2026-06-05T22:11:59.195001Z","end":"2026-06-05T22:11:59.346123Z","steps":["trace[306935411] 'process raft request' (duration: 106.452229ms)","trace[306935411] 'compare' (duration: 44.438509ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:59.34625Z","caller":"traceutil/trace.go:171","msg":"trace[1138511805] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"151.082463ms","start":"2026-06-05T22:11:59.195135Z","end":"2026-06-05T22:11:59.346217Z","steps":["trace[1138511805] 'process raft request' (duration: 150.897268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:59.346507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.455566ms","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-06-05T22:11:59.346567Z","caller":"traceutil/trace.go:171","msg":"trace[1051457861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1368; }","duration":"148.541028ms","start":"2026-06-05T22:11:59.198012Z","end":"2026-06-05T22:11:59.346553Z","steps":["trace[1051457861] 'agreement among raft nodes before linearized reading' (duration: 148.270592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:59.346795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.799524ms","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-06-05T22:11:59.347183Z","caller":"traceutil/trace.go:171","msg":"trace[1363588323] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1368; }","duration":"143.46917ms","start":"2026-06-05T22:11:59.203698Z","end":"2026-06-05T22:11:59.347167Z","steps":["trace[1363588323] 'agreement among raft nodes before linearized reading' (duration: 142.724802ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:59.500094Z","caller":"traceutil/trace.go:171","msg":"trace[1061918604] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"149.459142ms","start":"2026-06-05T22:11:59.350544Z","end":"2026-06-05T22:11:59.500003Z","steps":["trace[1061918604] 'process raft request' (duration: 110.632195ms)","trace[1061918604] 'compare' (duration: 38.623372ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:59.500187Z","caller":"traceutil/trace.go:171","msg":"trace[518154573] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"149.221516ms","start":"2026-06-05T22:11:59.350932Z","end":"2026-06-05T22:11:59.500153Z","steps":["trace[518154573] 'process raft request' (duration: 149.017491ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:12:09.943858Z","caller":"traceutil/trace.go:171","msg":"trace[1088511952] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"115.316972ms","start":"2026-06-05T22:12:09.828508Z","end":"2026-06-05T22:12:09.943825Z","steps":["trace[1088511952] 'process raft request' (duration: 55.995599ms)","trace[1088511952] 'compare' (duration: 59.167259ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:12:10.842272Z","caller":"traceutil/trace.go:171","msg":"trace[593334173] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"107.26155ms","start":"2026-06-05T22:12:10.734278Z","end":"2026-06-05T22:12:10.841539Z","steps":["trace[593334173] 'process raft request' (duration: 106.982483ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:12:10.887428Z","caller":"traceutil/trace.go:171","msg":"trace[448376712] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"121.102908ms","start":"2026-06-05T22:12:10.766299Z","end":"2026-06-05T22:12:10.887402Z","steps":["trace[448376712] 'process raft request' (duration: 120.991106ms)"],"step_count":1}