{"level":"warn","ts":"2026-07-23T23:43:10.093547Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T23:43:10.093636Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-23T23:43:10.093714Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T23:43:10.093727Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-23T23:43:10.093744Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-23T23:43:10.094406Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-07-23T23:43:10.094485Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-23T23:43:10.142789Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"21.713512ms"} {"level":"info","ts":"2026-07-23T23:43:10.180041Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-07-23T23:43:10.180165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T23:43:10.1802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-07-23T23:43:10.180254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T23:43:10.180289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-07-23T23:43:10.180359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-07-23T23:43:10.269313Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T23:43:10.272668Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T23:43:10.276128Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-23T23:43:10.281615Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T23:43:10.281792Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-23T23:43:10.281819Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T23:43:10.281895Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-23T23:43:10.281943Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-23T23:43:10.28405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-07-23T23:43:10.284165Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-23T23:43:10.285041Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-23T23:43:10.285114Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-23T23:43:10.285145Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-23T23:43:10.285889Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T23:43:10.285917Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T23:43:11.094914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T23:43:11.094965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T23:43:11.094994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-07-23T23:43:11.095009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-07-23T23:43:11.095017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-23T23:43:11.095039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-07-23T23:43:11.095055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-23T23:43:11.115547Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T23:43:11.115602Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:43:11.115934Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:43:11.116025Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:43:11.116571Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T23:43:11.116664Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T23:43:11.117506Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-07-23T23:43:11.119248Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-23T23:43:11.17863Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:43:11.178744Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:43:11.178799Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:43:12.771536Z","caller":"traceutil/trace.go:171","msg":"trace[693571640] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"114.226534ms","start":"2026-07-23T23:43:12.657276Z","end":"2026-07-23T23:43:12.771502Z","steps":["trace[693571640] 'process raft request' (duration: 74.637598ms)","trace[693571640] 'compare' (duration: 39.383788ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:12.771862Z","caller":"traceutil/trace.go:171","msg":"trace[343876374] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"104.937014ms","start":"2026-07-23T23:43:12.666891Z","end":"2026-07-23T23:43:12.771828Z","steps":["trace[343876374] 'process raft request' (duration: 104.562542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:23.536578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.423527ms","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-07-23T23:43:23.536628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.312253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-07-23T23:43:23.536692Z","caller":"traceutil/trace.go:171","msg":"trace[594253628] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:398; }","duration":"281.569751ms","start":"2026-07-23T23:43:23.255102Z","end":"2026-07-23T23:43:23.536671Z","steps":["trace[594253628] 'range keys from in-memory index tree' (duration: 281.357946ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:23.53672Z","caller":"traceutil/trace.go:171","msg":"trace[628961477] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:398; }","duration":"355.467257ms","start":"2026-07-23T23:43:23.181233Z","end":"2026-07-23T23:43:23.5367Z","steps":["trace[628961477] 'range keys from in-memory index tree' (duration: 355.189008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:23.536767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:23.181214Z","time spent":"355.54323ms","remote":"127.0.0.1:46546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-07-23T23:43:33.556984Z","caller":"traceutil/trace.go:171","msg":"trace[932437072] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"107.622032ms","start":"2026-07-23T23:43:33.449333Z","end":"2026-07-23T23:43:33.556955Z","steps":["trace[932437072] 'process raft request' (duration: 107.440918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:35.776194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.911339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-23T23:43:35.776281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.098753178s","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-07-23T23:43:35.776301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.17306575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T23:43:35.776378Z","caller":"traceutil/trace.go:171","msg":"trace[760511543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:430; }","duration":"2.098910923s","start":"2026-07-23T23:43:33.677442Z","end":"2026-07-23T23:43:35.776353Z","steps":["trace[760511543] 'range keys from in-memory index tree' (duration: 2.098632354s)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:35.776396Z","caller":"traceutil/trace.go:171","msg":"trace[2063997274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:430; }","duration":"2.173215195s","start":"2026-07-23T23:43:33.603159Z","end":"2026-07-23T23:43:35.776375Z","steps":["trace[2063997274] 'range keys from in-memory index tree' (duration: 2.172958228s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:35.776434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22580501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-23T23:43:35.776444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:33.677413Z","time spent":"2.099021996s","remote":"127.0.0.1:46658","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-07-23T23:43:35.776476Z","caller":"traceutil/trace.go:171","msg":"trace[1669162248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:430; }","duration":"1.225882172s","start":"2026-07-23T23:43:34.550583Z","end":"2026-07-23T23:43:35.776465Z","steps":["trace[1669162248] 'range keys from in-memory index tree' (duration: 1.225673877s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:35.776383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656575399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T23:43:35.77652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.148882642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-23T23:43:35.776525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:34.550562Z","time spent":"1.225952573s","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-23T23:43:35.776555Z","caller":"traceutil/trace.go:171","msg":"trace[1934975758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"1.657249706s","start":"2026-07-23T23:43:34.119281Z","end":"2026-07-23T23:43:35.776531Z","steps":["trace[1934975758] 'range keys from in-memory index tree' (duration: 1.656525688s)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:35.776565Z","caller":"traceutil/trace.go:171","msg":"trace[1255827892] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:430; }","duration":"2.148947284s","start":"2026-07-23T23:43:33.627608Z","end":"2026-07-23T23:43:35.776555Z","steps":["trace[1255827892] 'range keys from in-memory index tree' (duration: 2.148775829s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:35.776599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:33.627596Z","time spent":"2.148996705s","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T23:43:35.776605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:34.119266Z","time spent":"1.657326337s","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:43:35.776633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091505716s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:43:35.776844Z","caller":"traceutil/trace.go:171","msg":"trace[189384669] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:430; }","duration":"1.091759972s","start":"2026-07-23T23:43:34.68504Z","end":"2026-07-23T23:43:35.7768Z","steps":["trace[189384669] 'range keys from in-memory index tree' (duration: 1.091484596s)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:35.776327Z","caller":"traceutil/trace.go:171","msg":"trace[451974373] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:430; }","duration":"785.071333ms","start":"2026-07-23T23:43:34.991225Z","end":"2026-07-23T23:43:35.776297Z","steps":["trace[451974373] 'range keys from in-memory index tree' (duration: 784.780277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:35.777028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:34.99117Z","time spent":"785.840294ms","remote":"127.0.0.1:46394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-07-23T23:43:35.776444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:33.603139Z","time spent":"2.173296478s","remote":"127.0.0.1:46658","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-07-23T23:43:35.932014Z","caller":"traceutil/trace.go:171","msg":"trace[1611661617] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"153.60954ms","start":"2026-07-23T23:43:35.778372Z","end":"2026-07-23T23:43:35.931981Z","steps":["trace[1611661617] 'read index received' (duration: 153.525728ms)","trace[1611661617] 'applied index is now lower than readState.Index' (duration: 82.052µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:43:35.932194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.773824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:43:35.932242Z","caller":"traceutil/trace.go:171","msg":"trace[1618280173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"153.893727ms","start":"2026-07-23T23:43:35.778332Z","end":"2026-07-23T23:43:35.932226Z","steps":["trace[1618280173] 'agreement among raft nodes before linearized reading' (duration: 153.770574ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:36.018894Z","caller":"traceutil/trace.go:171","msg":"trace[605443072] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"237.4593ms","start":"2026-07-23T23:43:35.781385Z","end":"2026-07-23T23:43:36.018844Z","steps":["trace[605443072] 'process raft request' (duration: 214.104306ms)","trace[605443072] 'compare' (duration: 23.195171ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:36.019192Z","caller":"traceutil/trace.go:171","msg":"trace[700443825] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"237.116862ms","start":"2026-07-23T23:43:35.782066Z","end":"2026-07-23T23:43:36.019183Z","steps":["trace[700443825] 'process raft request' (duration: 236.743123ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:36.019647Z","caller":"traceutil/trace.go:171","msg":"trace[212124266] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"237.096031ms","start":"2026-07-23T23:43:35.782529Z","end":"2026-07-23T23:43:36.019625Z","steps":["trace[212124266] 'process raft request' (duration: 236.963528ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:36.019891Z","caller":"traceutil/trace.go:171","msg":"trace[1432095206] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"236.467055ms","start":"2026-07-23T23:43:35.783412Z","end":"2026-07-23T23:43:36.019879Z","steps":["trace[1432095206] 'process raft request' (duration: 236.162988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:38.878621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.956994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:43:38.878734Z","caller":"traceutil/trace.go:171","msg":"trace[640189301] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"112.809423ms","start":"2026-07-23T23:43:38.765907Z","end":"2026-07-23T23:43:38.878717Z","steps":["trace[640189301] 'process raft request' (duration: 10.230775ms)","trace[640189301] 'compare' (duration: 101.757279ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:38.889809Z","caller":"traceutil/trace.go:171","msg":"trace[651308766] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"119.143296ms","start":"2026-07-23T23:43:38.770644Z","end":"2026-07-23T23:43:38.889788Z","steps":["trace[651308766] 'process raft request' (duration: 119.013393ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:38.889913Z","caller":"traceutil/trace.go:171","msg":"trace[2138638900] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"118.206123ms","start":"2026-07-23T23:43:38.771695Z","end":"2026-07-23T23:43:38.889901Z","steps":["trace[2138638900] 'process raft request' (duration: 118.09163ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:38.889906Z","caller":"traceutil/trace.go:171","msg":"trace[1077841771] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"118.201153ms","start":"2026-07-23T23:43:38.771679Z","end":"2026-07-23T23:43:38.88988Z","steps":["trace[1077841771] 'process raft request' (duration: 118.06941ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:38.889954Z","caller":"traceutil/trace.go:171","msg":"trace[1150675278] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"115.421077ms","start":"2026-07-23T23:43:38.77448Z","end":"2026-07-23T23:43:38.889901Z","steps":["trace[1150675278] 'process raft request' (duration: 115.370336ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:42.189082Z","caller":"traceutil/trace.go:171","msg":"trace[817958700] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"126.275039ms","start":"2026-07-23T23:43:42.062784Z","end":"2026-07-23T23:43:42.189059Z","steps":["trace[817958700] 'read index received' (duration: 126.115565ms)","trace[817958700] 'applied index is now lower than readState.Index' (duration: 158.564µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:43:42.189246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.433952ms","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-07-23T23:43:42.189218Z","caller":"traceutil/trace.go:171","msg":"trace[1232354619] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"126.466173ms","start":"2026-07-23T23:43:42.062703Z","end":"2026-07-23T23:43:42.189169Z","steps":["trace[1232354619] 'process raft request' (duration: 126.204407ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:42.189302Z","caller":"traceutil/trace.go:171","msg":"trace[1071838987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:477; }","duration":"126.515304ms","start":"2026-07-23T23:43:42.062774Z","end":"2026-07-23T23:43:42.189289Z","steps":["trace[1071838987] 'agreement among raft nodes before linearized reading' (duration: 126.408512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:42.689518Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411946933679641,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:43:42.70795Z","caller":"traceutil/trace.go:171","msg":"trace[180243465] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"642.508723ms","start":"2026-07-23T23:43:42.065416Z","end":"2026-07-23T23:43:42.707925Z","steps":["trace[180243465] 'process raft request' (duration: 642.301079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:42.708091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:42.065407Z","time spent":"642.599985ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:43:42.708092Z","caller":"traceutil/trace.go:171","msg":"trace[1398109932] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"642.540503ms","start":"2026-07-23T23:43:42.065528Z","end":"2026-07-23T23:43:42.708069Z","steps":["trace[1398109932] 'process raft request' (duration: 642.357669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:42.708222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:42.065523Z","time spent":"642.624445ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:43:43.206843Z","caller":"traceutil/trace.go:171","msg":"trace[582427300] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:495; }","duration":"1.017662872s","start":"2026-07-23T23:43:42.189157Z","end":"2026-07-23T23:43:43.20682Z","steps":["trace[582427300] 'read index received' (duration: 518.701761ms)","trace[582427300] 'applied index is now lower than readState.Index' (duration: 498.959771ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:43.206864Z","caller":"traceutil/trace.go:171","msg":"trace[181560535] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.011818426s","start":"2026-07-23T23:43:42.195013Z","end":"2026-07-23T23:43:43.206832Z","steps":["trace[181560535] 'process raft request' (duration: 1.00543515s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:43.206959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09477177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:43:43.207002Z","caller":"traceutil/trace.go:171","msg":"trace[70691918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:480; }","duration":"1.094855853s","start":"2026-07-23T23:43:42.112136Z","end":"2026-07-23T23:43:43.206992Z","steps":["trace[70691918] 'agreement among raft nodes before linearized reading' (duration: 1.094777091s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:43.207046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:42.194995Z","time spent":"1.01196463s","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:43:43.207055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:42.11212Z","time spent":"1.094927474s","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-23T23:43:45.122858Z","caller":"traceutil/trace.go:171","msg":"trace[1546177096] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"119.703914ms","start":"2026-07-23T23:43:45.003111Z","end":"2026-07-23T23:43:45.122815Z","steps":["trace[1546177096] 'process raft request' (duration: 115.12112ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:48.387672Z","caller":"traceutil/trace.go:171","msg":"trace[764903505] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"127.666588ms","start":"2026-07-23T23:43:48.259983Z","end":"2026-07-23T23:43:48.38765Z","steps":["trace[764903505] 'process raft request' (duration: 127.530255ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:48.90267Z","caller":"traceutil/trace.go:171","msg":"trace[2053897515] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"140.262956ms","start":"2026-07-23T23:43:48.762384Z","end":"2026-07-23T23:43:48.902647Z","steps":["trace[2053897515] 'process raft request' (duration: 140.135083ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:48.906886Z","caller":"traceutil/trace.go:171","msg":"trace[1025937189] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"142.504745ms","start":"2026-07-23T23:43:48.764237Z","end":"2026-07-23T23:43:48.906741Z","steps":["trace[1025937189] 'process raft request' (duration: 141.692207ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:50.525054Z","caller":"traceutil/trace.go:171","msg":"trace[1436450957] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"133.126328ms","start":"2026-07-23T23:43:50.391904Z","end":"2026-07-23T23:43:50.52503Z","steps":["trace[1436450957] 'read index received' (duration: 132.144238ms)","trace[1436450957] 'applied index is now lower than readState.Index' (duration: 981.23µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:43:50.525316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.332112ms","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-07-23T23:43:50.525301Z","caller":"traceutil/trace.go:171","msg":"trace[2123005966] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"259.516522ms","start":"2026-07-23T23:43:50.265629Z","end":"2026-07-23T23:43:50.525145Z","steps":["trace[2123005966] 'process raft request' (duration: 259.112993ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:50.52538Z","caller":"traceutil/trace.go:171","msg":"trace[852284372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"133.520456ms","start":"2026-07-23T23:43:50.391845Z","end":"2026-07-23T23:43:50.525366Z","steps":["trace[852284372] 'agreement among raft nodes before linearized reading' (duration: 133.309632ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:50.718235Z","caller":"traceutil/trace.go:171","msg":"trace[1928401613] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"171.933348ms","start":"2026-07-23T23:43:50.546273Z","end":"2026-07-23T23:43:50.718206Z","steps":["trace[1928401613] 'process raft request' (duration: 171.883257ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:50.718672Z","caller":"traceutil/trace.go:171","msg":"trace[162630084] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"172.482819ms","start":"2026-07-23T23:43:50.546168Z","end":"2026-07-23T23:43:50.718651Z","steps":["trace[162630084] 'process raft request' (duration: 171.926807ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:50.718644Z","caller":"traceutil/trace.go:171","msg":"trace[1970124553] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"189.083358ms","start":"2026-07-23T23:43:50.529517Z","end":"2026-07-23T23:43:50.718601Z","steps":["trace[1970124553] 'process raft request' (duration: 159.421997ms)","trace[1970124553] 'compare' (duration: 28.973927ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:43:56.107677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.921269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:43:56.107766Z","caller":"traceutil/trace.go:171","msg":"trace[79246573] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"714.635236ms","start":"2026-07-23T23:43:55.393105Z","end":"2026-07-23T23:43:56.10774Z","steps":["trace[79246573] 'read index received' (duration: 109.540313ms)","trace[79246573] 'applied index is now lower than readState.Index' (duration: 605.093903ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:56.107809Z","caller":"traceutil/trace.go:171","msg":"trace[1324197916] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.060382826s","start":"2026-07-23T23:43:55.047394Z","end":"2026-07-23T23:43:56.107777Z","steps":["trace[1324197916] 'process raft request' (duration: 455.304534ms)","trace[1324197916] 'compare' (duration: 604.831407ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:43:56.107882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.786859ms","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-07-23T23:43:56.107901Z","caller":"traceutil/trace.go:171","msg":"trace[1131817951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"714.84901ms","start":"2026-07-23T23:43:55.393044Z","end":"2026-07-23T23:43:56.107894Z","steps":["trace[1131817951] 'agreement among raft nodes before linearized reading' (duration: 714.766088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:56.107919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:55.393021Z","time spent":"714.89409ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T23:43:56.107903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.464664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T23:43:56.107957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:55.04737Z","time spent":"1.060508209s","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:43:56.107973Z","caller":"traceutil/trace.go:171","msg":"trace[771584426] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:617; }","duration":"341.583027ms","start":"2026-07-23T23:43:55.766371Z","end":"2026-07-23T23:43:56.107954Z","steps":["trace[771584426] 'agreement among raft nodes before linearized reading' (duration: 341.472095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:56.108012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:55.766351Z","time spent":"341.654009ms","remote":"127.0.0.1:46576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"warn","ts":"2026-07-23T23:43:58.369311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.058096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:43:58.369423Z","caller":"traceutil/trace.go:171","msg":"trace[623616401] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"189.680119ms","start":"2026-07-23T23:43:58.179724Z","end":"2026-07-23T23:43:58.369404Z","steps":["trace[623616401] 'process raft request' (duration: 42.448419ms)","trace[623616401] 'compare' (duration: 146.942474ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:58.783913Z","caller":"traceutil/trace.go:171","msg":"trace[122870133] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"191.14275ms","start":"2026-07-23T23:43:58.592739Z","end":"2026-07-23T23:43:58.783882Z","steps":["trace[122870133] 'process raft request' (duration: 187.598408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:03.073574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.559177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T23:44:03.073637Z","caller":"traceutil/trace.go:171","msg":"trace[966652212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"272.664959ms","start":"2026-07-23T23:44:02.800958Z","end":"2026-07-23T23:44:03.073623Z","steps":["trace[966652212] 'range keys from in-memory index tree' (duration: 272.434675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:03.07361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.88177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bf4kl\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-23T23:44:03.073768Z","caller":"traceutil/trace.go:171","msg":"trace[1861979565] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bf4kl; range_end:; response_count:1; response_revision:657; }","duration":"150.025353ms","start":"2026-07-23T23:44:02.923655Z","end":"2026-07-23T23:44:03.07368Z","steps":["trace[1861979565] 'range keys from in-memory index tree' (duration: 149.686866ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:03.587839Z","caller":"traceutil/trace.go:171","msg":"trace[706528620] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"509.043187ms","start":"2026-07-23T23:44:03.078775Z","end":"2026-07-23T23:44:03.587818Z","steps":["trace[706528620] 'process raft request' (duration: 508.875154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:03.587957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:03.078754Z","time spent":"509.14464ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:44:03.588021Z","caller":"traceutil/trace.go:171","msg":"trace[1480895255] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:684; }","duration":"476.089768ms","start":"2026-07-23T23:44:03.111898Z","end":"2026-07-23T23:44:03.587987Z","steps":["trace[1480895255] 'read index received' (duration: 13.104856ms)","trace[1480895255] 'applied index is now lower than readState.Index' (duration: 462.983052ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:03.588055Z","caller":"traceutil/trace.go:171","msg":"trace[811864369] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"497.923982ms","start":"2026-07-23T23:44:03.090124Z","end":"2026-07-23T23:44:03.588048Z","steps":["trace[811864369] 'process raft request' (duration: 497.657157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:03.588183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.273071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:44:03.588222Z","caller":"traceutil/trace.go:171","msg":"trace[624803414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"476.330852ms","start":"2026-07-23T23:44:03.111882Z","end":"2026-07-23T23:44:03.588213Z","steps":["trace[624803414] 'agreement among raft nodes before linearized reading' (duration: 476.21335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:03.588256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:03.111827Z","time spent":"476.421664ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:44:03.588142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:03.090103Z","time spent":"498.018653ms","remote":"127.0.0.1:46546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:44:04.313465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.460024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:44:04.313592Z","caller":"traceutil/trace.go:171","msg":"trace[1901736857] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"155.096396ms","start":"2026-07-23T23:44:04.158465Z","end":"2026-07-23T23:44:04.313561Z","steps":["trace[1901736857] 'read index received' (duration: 44.479029ms)","trace[1901736857] 'applied index is now lower than readState.Index' (duration: 110.615567ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:04.313688Z","caller":"traceutil/trace.go:171","msg":"trace[323840709] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"388.012016ms","start":"2026-07-23T23:44:03.92566Z","end":"2026-07-23T23:44:04.313672Z","steps":["trace[323840709] 'process raft request' (duration: 277.235536ms)","trace[323840709] 'compare' (duration: 110.130108ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:44:04.31375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:03.925627Z","time spent":"388.087848ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:44:04.314044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.559076ms","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-07-23T23:44:04.314112Z","caller":"traceutil/trace.go:171","msg":"trace[1879774240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"155.674017ms","start":"2026-07-23T23:44:04.158422Z","end":"2026-07-23T23:44:04.314096Z","steps":["trace[1879774240] 'agreement among raft nodes before linearized reading' (duration: 155.506774ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:04.599268Z","caller":"traceutil/trace.go:171","msg":"trace[224430428] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"182.525855ms","start":"2026-07-23T23:44:04.416714Z","end":"2026-07-23T23:44:04.59924Z","steps":["trace[224430428] 'process raft request' (duration: 165.000497ms)","trace[224430428] 'compare' (duration: 17.416676ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:44:07.109942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.981241226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T23:44:07.110021Z","caller":"traceutil/trace.go:171","msg":"trace[708160222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:663; }","duration":"1.981371559s","start":"2026-07-23T23:44:05.128632Z","end":"2026-07-23T23:44:07.110003Z","steps":["trace[708160222] 'range keys from in-memory index tree' (duration: 1.980973571s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:07.110065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:05.128617Z","time spent":"1.98143869s","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T23:44:07.110105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51716304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T23:44:07.110194Z","caller":"traceutil/trace.go:171","msg":"trace[229719231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"1.517322603s","start":"2026-07-23T23:44:05.59285Z","end":"2026-07-23T23:44:07.110173Z","steps":["trace[229719231] 'range keys from in-memory index tree' (duration: 1.516977226s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:07.110246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:05.592828Z","time spent":"1.517408704s","remote":"127.0.0.1:46658","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-07-23T23:44:07.11046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998291323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:44:07.110494Z","caller":"traceutil/trace.go:171","msg":"trace[1274910627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"1.998361315s","start":"2026-07-23T23:44:05.112122Z","end":"2026-07-23T23:44:07.110483Z","steps":["trace[1274910627] 'range keys from in-memory index tree' (duration: 1.998226722s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:07.11052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:05.112102Z","time spent":"1.998412315s","remote":"127.0.0.1:46360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:44:07.110797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.402851ms","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-07-23T23:44:07.110832Z","caller":"traceutil/trace.go:171","msg":"trace[1445550025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:663; }","duration":"781.479823ms","start":"2026-07-23T23:44:06.329342Z","end":"2026-07-23T23:44:07.110822Z","steps":["trace[1445550025] 'range keys from in-memory index tree' (duration: 781.231038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:07.110879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:06.32932Z","time spent":"781.551094ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T23:44:07.110912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.790806ms","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-07-23T23:44:07.110947Z","caller":"traceutil/trace.go:171","msg":"trace[1713941910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:663; }","duration":"507.866517ms","start":"2026-07-23T23:44:06.603071Z","end":"2026-07-23T23:44:07.110938Z","steps":["trace[1713941910] 'range keys from in-memory index tree' (duration: 507.707834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:07.110974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:06.603051Z","time spent":"507.916638ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T23:44:07.111036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.117927446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-23T23:44:07.111146Z","caller":"traceutil/trace.go:171","msg":"trace[745517347] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:663; }","duration":"2.118415106s","start":"2026-07-23T23:44:04.992709Z","end":"2026-07-23T23:44:07.111124Z","steps":["trace[745517347] 'range keys from in-memory index tree' (duration: 2.117742263s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:07.111197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:04.992689Z","time spent":"2.118491108s","remote":"127.0.0.1:46394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-07-23T23:44:07.414356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:07.11405Z","time spent":"300.30046ms","remote":"127.0.0.1:46394","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-23T23:44:07.533056Z","caller":"traceutil/trace.go:171","msg":"trace[1471609530] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"415.804082ms","start":"2026-07-23T23:44:07.117229Z","end":"2026-07-23T23:44:07.533033Z","steps":["trace[1471609530] 'process raft request' (duration: 415.741001ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:07.53307Z","caller":"traceutil/trace.go:171","msg":"trace[750978090] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"416.72663ms","start":"2026-07-23T23:44:07.116283Z","end":"2026-07-23T23:44:07.533009Z","steps":["trace[750978090] 'process raft request' (duration: 416.398094ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:07.533148Z","caller":"traceutil/trace.go:171","msg":"trace[2067508457] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"416.490426ms","start":"2026-07-23T23:44:07.116623Z","end":"2026-07-23T23:44:07.533113Z","steps":["trace[2067508457] 'process raft request' (duration: 416.288792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:07.533197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:07.117208Z","time spent":"415.918015ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:44:07.53325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:07.11626Z","time spent":"416.904693ms","remote":"127.0.0.1:46440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:44:07.533345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:07.116612Z","time spent":"416.615658ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:44:07.533443Z","caller":"traceutil/trace.go:171","msg":"trace[1121738305] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"416.136579ms","start":"2026-07-23T23:44:07.11729Z","end":"2026-07-23T23:44:07.533426Z","steps":["trace[1121738305] 'process raft request' (duration: 415.713301ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:07.533465Z","caller":"traceutil/trace.go:171","msg":"trace[335241134] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"416.094218ms","start":"2026-07-23T23:44:07.117357Z","end":"2026-07-23T23:44:07.533451Z","steps":["trace[335241134] 'process raft request' (duration: 415.975956ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:07.533473Z","caller":"traceutil/trace.go:171","msg":"trace[1036458332] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:691; }","duration":"414.65274ms","start":"2026-07-23T23:44:07.118795Z","end":"2026-07-23T23:44:07.533447Z","steps":["trace[1036458332] 'read index received' (duration: 295.52343ms)","trace[1036458332] 'applied index is now lower than readState.Index' (duration: 119.12479ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:44:07.533527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:07.117348Z","time spent":"416.147299ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:44:07.533606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.798532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T23:44:07.53355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:07.117281Z","time spent":"416.22649ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:44:07.53364Z","caller":"traceutil/trace.go:171","msg":"trace[456616772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"414.886904ms","start":"2026-07-23T23:44:07.118741Z","end":"2026-07-23T23:44:07.533628Z","steps":["trace[456616772] 'agreement among raft nodes before linearized reading' (duration: 414.808463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:07.533675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:07.118721Z","time spent":"414.946476ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-23T23:44:07.55145Z","caller":"traceutil/trace.go:171","msg":"trace[1987658301] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"135.935136ms","start":"2026-07-23T23:44:07.415481Z","end":"2026-07-23T23:44:07.551416Z","steps":["trace[1987658301] 'process raft request' (duration: 135.783813ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:08.053369Z","caller":"traceutil/trace.go:171","msg":"trace[252080318] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"104.658089ms","start":"2026-07-23T23:44:07.948691Z","end":"2026-07-23T23:44:08.053349Z","steps":["trace[252080318] 'process raft request' (duration: 102.486549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:09.687362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.398842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T23:44:09.687427Z","caller":"traceutil/trace.go:171","msg":"trace[1611301734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:678; }","duration":"148.485284ms","start":"2026-07-23T23:44:09.538924Z","end":"2026-07-23T23:44:09.68741Z","steps":["trace[1611301734] 'range keys from in-memory index tree' (duration: 148.205228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:09.687435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.857721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-23T23:44:09.687547Z","caller":"traceutil/trace.go:171","msg":"trace[1573997051] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:678; }","duration":"147.978303ms","start":"2026-07-23T23:44:09.539541Z","end":"2026-07-23T23:44:09.687519Z","steps":["trace[1573997051] 'range keys from in-memory index tree' (duration: 147.639308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:09.687603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.622596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-23T23:44:09.687677Z","caller":"traceutil/trace.go:171","msg":"trace[998136414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:678; }","duration":"148.741168ms","start":"2026-07-23T23:44:09.538918Z","end":"2026-07-23T23:44:09.68766Z","steps":["trace[998136414] 'range keys from in-memory index tree' (duration: 148.471123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:09.687799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.692057ms","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-07-23T23:44:09.687934Z","caller":"traceutil/trace.go:171","msg":"trace[2023308686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:678; }","duration":"148.985073ms","start":"2026-07-23T23:44:09.538924Z","end":"2026-07-23T23:44:09.687909Z","steps":["trace[2023308686] 'range keys from in-memory index tree' (duration: 148.32524ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:10.300389Z","caller":"traceutil/trace.go:171","msg":"trace[560282444] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"167.911551ms","start":"2026-07-23T23:44:10.132456Z","end":"2026-07-23T23:44:10.300367Z","steps":["trace[560282444] 'process raft request' (duration: 167.86621ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:10.300474Z","caller":"traceutil/trace.go:171","msg":"trace[1961226984] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"168.272179ms","start":"2026-07-23T23:44:10.132168Z","end":"2026-07-23T23:44:10.300441Z","steps":["trace[1961226984] 'process raft request' (duration: 144.197474ms)","trace[1961226984] 'compare' (duration: 23.83116ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:11.034053Z","caller":"traceutil/trace.go:171","msg":"trace[1263777722] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"113.257989ms","start":"2026-07-23T23:44:10.920767Z","end":"2026-07-23T23:44:11.034025Z","steps":["trace[1263777722] 'process raft request' (duration: 112.994814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:11.177177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.431806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:44:11.177309Z","caller":"traceutil/trace.go:171","msg":"trace[267870541] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"211.615537ms","start":"2026-07-23T23:44:10.965673Z","end":"2026-07-23T23:44:11.177289Z","steps":["trace[267870541] 'process raft request' (duration: 101.988066ms)","trace[267870541] 'compare' (duration: 109.206732ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:11.185153Z","caller":"traceutil/trace.go:171","msg":"trace[1975043363] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"146.576558ms","start":"2026-07-23T23:44:11.038554Z","end":"2026-07-23T23:44:11.185131Z","steps":["trace[1975043363] 'process raft request' (duration: 146.428385ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:11.185435Z","caller":"traceutil/trace.go:171","msg":"trace[113916174] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"141.954142ms","start":"2026-07-23T23:44:11.043441Z","end":"2026-07-23T23:44:11.185395Z","steps":["trace[113916174] 'process raft request' (duration: 141.638747ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:11.318048Z","caller":"traceutil/trace.go:171","msg":"trace[1760813310] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:738; }","duration":"131.763896ms","start":"2026-07-23T23:44:11.186268Z","end":"2026-07-23T23:44:11.318032Z","steps":["trace[1760813310] 'read index received' (duration: 131.116254ms)","trace[1760813310] 'applied index is now lower than readState.Index' (duration: 646.932µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:11.318115Z","caller":"traceutil/trace.go:171","msg":"trace[1275278374] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"198.290195ms","start":"2026-07-23T23:44:11.11982Z","end":"2026-07-23T23:44:11.31811Z","steps":["trace[1275278374] 'process raft request' (duration: 197.552632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:11.318455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.160373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:44:11.318533Z","caller":"traceutil/trace.go:171","msg":"trace[1997230235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:710; }","duration":"132.287345ms","start":"2026-07-23T23:44:11.186228Z","end":"2026-07-23T23:44:11.318516Z","steps":["trace[1997230235] 'agreement among raft nodes before linearized reading' (duration: 131.913769ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:11.331291Z","caller":"traceutil/trace.go:171","msg":"trace[1144084411] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"139.836633ms","start":"2026-07-23T23:44:11.19144Z","end":"2026-07-23T23:44:11.331277Z","steps":["trace[1144084411] 'process raft request' (duration: 139.64818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:11.331393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.555225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-kxmxf\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-07-23T23:44:11.331503Z","caller":"traceutil/trace.go:171","msg":"trace[1656809122] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-kxmxf; range_end:; response_count:1; response_revision:711; }","duration":"142.713767ms","start":"2026-07-23T23:44:11.188766Z","end":"2026-07-23T23:44:11.33148Z","steps":["trace[1656809122] 'agreement among raft nodes before linearized reading' (duration: 142.448371ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:22.071593Z","caller":"traceutil/trace.go:171","msg":"trace[817612216] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"179.870141ms","start":"2026-07-23T23:44:21.891693Z","end":"2026-07-23T23:44:22.071564Z","steps":["trace[817612216] 'process raft request' (duration: 179.824691ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:22.071661Z","caller":"traceutil/trace.go:171","msg":"trace[80874467] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"179.946103ms","start":"2026-07-23T23:44:21.891686Z","end":"2026-07-23T23:44:22.071632Z","steps":["trace[80874467] 'process raft request' (duration: 179.678158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:23.649628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411946933680377,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:44:24.150267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411946933680377,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:44:24.651167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411946933680377,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:44:24.681874Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.643212887s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T23:44:24.682445Z","caller":"traceutil/trace.go:171","msg":"trace[92860919] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:857; }","duration":"1.533235029s","start":"2026-07-23T23:44:23.149189Z","end":"2026-07-23T23:44:24.682424Z","steps":["trace[92860919] 'read index received' (duration: 1.532839492s)","trace[92860919] 'applied index is now lower than readState.Index' (duration: 394.497µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:24.682536Z","caller":"traceutil/trace.go:171","msg":"trace[1568280284] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"1.643929938s","start":"2026-07-23T23:44:23.038568Z","end":"2026-07-23T23:44:24.682498Z","steps":["trace[1568280284] 'process raft request' (duration: 1.643570452s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:24.68266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474227753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-23T23:44:24.682736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:23.038551Z","time spent":"1.644101462s","remote":"127.0.0.1:46546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:44:24.682752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.272423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-23T23:44:24.682816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.79423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:44:24.682839Z","caller":"traceutil/trace.go:171","msg":"trace[36428057] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:825; }","duration":"458.411745ms","start":"2026-07-23T23:44:24.224402Z","end":"2026-07-23T23:44:24.682813Z","steps":["trace[36428057] 'agreement among raft nodes before linearized reading' (duration: 458.251343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:24.682909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:24.224382Z","time spent":"458.508506ms","remote":"127.0.0.1:46890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-07-23T23:44:24.682913Z","caller":"traceutil/trace.go:171","msg":"trace[2129469264] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:825; }","duration":"683.934712ms","start":"2026-07-23T23:44:23.998961Z","end":"2026-07-23T23:44:24.682896Z","steps":["trace[2129469264] 'agreement among raft nodes before linearized reading' (duration: 683.755949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:24.682925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.378282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-23T23:44:24.682975Z","caller":"traceutil/trace.go:171","msg":"trace[1069411714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:825; }","duration":"605.476954ms","start":"2026-07-23T23:44:24.077485Z","end":"2026-07-23T23:44:24.682962Z","steps":["trace[1069411714] 'agreement among raft nodes before linearized reading' (duration: 605.338491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:24.682977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:23.998926Z","time spent":"684.035104ms","remote":"127.0.0.1:46476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:44:24.68301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.443595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-23T23:44:24.683019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:24.07746Z","time spent":"605.551215ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-23T23:44:24.683103Z","caller":"traceutil/trace.go:171","msg":"trace[1184983274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:825; }","duration":"705.557106ms","start":"2026-07-23T23:44:23.977495Z","end":"2026-07-23T23:44:24.683053Z","steps":["trace[1184983274] 'agreement among raft nodes before linearized reading' (duration: 705.257331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:24.683174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:23.97748Z","time spent":"705.657268ms","remote":"127.0.0.1:46658","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-07-23T23:44:24.683188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.816392ms","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-07-23T23:44:24.683353Z","caller":"traceutil/trace.go:171","msg":"trace[2105656266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:825; }","duration":"605.089237ms","start":"2026-07-23T23:44:24.078245Z","end":"2026-07-23T23:44:24.683334Z","steps":["trace[2105656266] 'agreement among raft nodes before linearized reading' (duration: 604.384435ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:24.682707Z","caller":"traceutil/trace.go:171","msg":"trace[1772101183] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:825; }","duration":"1.474363635s","start":"2026-07-23T23:44:23.208332Z","end":"2026-07-23T23:44:24.682696Z","steps":["trace[1772101183] 'agreement among raft nodes before linearized reading' (duration: 1.474247702s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:24.68344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.928685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-23T23:44:24.683493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:24.078214Z","time spent":"605.224249ms","remote":"127.0.0.1:46658","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-07-23T23:44:24.683508Z","caller":"traceutil/trace.go:171","msg":"trace[461569346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:825; }","duration":"791.997097ms","start":"2026-07-23T23:44:23.891472Z","end":"2026-07-23T23:44:24.683469Z","steps":["trace[461569346] 'agreement among raft nodes before linearized reading' (duration: 791.822014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:24.683866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:23.891457Z","time spent":"792.383623ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T23:44:24.683419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.581823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-23T23:44:24.684121Z","caller":"traceutil/trace.go:171","msg":"trace[1631659549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:825; }","duration":"498.340875ms","start":"2026-07-23T23:44:24.185763Z","end":"2026-07-23T23:44:24.684104Z","steps":["trace[1631659549] 'agreement among raft nodes before linearized reading' (duration: 497.589553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:24.684217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:24.185733Z","time spent":"498.451767ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:44:24.683483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533422003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:44:24.684487Z","caller":"traceutil/trace.go:171","msg":"trace[1209401573] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:825; }","duration":"1.535328113s","start":"2026-07-23T23:44:23.149143Z","end":"2026-07-23T23:44:24.684471Z","steps":["trace[1209401573] 'agreement among raft nodes before linearized reading' (duration: 1.533404071s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:24.684611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:23.149125Z","time spent":"1.535451926s","remote":"127.0.0.1:46714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:44:24.683523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:23.208301Z","time spent":"1.475173848s","remote":"127.0.0.1:46920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:44:24.683398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.368635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:44:24.686081Z","caller":"traceutil/trace.go:171","msg":"trace[1599744368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:825; }","duration":"573.052229ms","start":"2026-07-23T23:44:24.112993Z","end":"2026-07-23T23:44:24.686046Z","steps":["trace[1599744368] 'agreement among raft nodes before linearized reading' (duration: 570.365394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:24.686206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:24.11298Z","time spent":"573.205892ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-23T23:44:28.458817Z","caller":"traceutil/trace.go:171","msg":"trace[2107434866] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"106.573265ms","start":"2026-07-23T23:44:28.352218Z","end":"2026-07-23T23:44:28.458792Z","steps":["trace[2107434866] 'process raft request' (duration: 100.026938ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:28.909244Z","caller":"traceutil/trace.go:171","msg":"trace[618157541] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"106.992151ms","start":"2026-07-23T23:44:28.802216Z","end":"2026-07-23T23:44:28.909208Z","steps":["trace[618157541] 'read index received' (duration: 106.745507ms)","trace[618157541] 'applied index is now lower than readState.Index' (duration: 245.454µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:28.909401Z","caller":"traceutil/trace.go:171","msg":"trace[738683744] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"107.232685ms","start":"2026-07-23T23:44:28.802155Z","end":"2026-07-23T23:44:28.909387Z","steps":["trace[738683744] 'process raft request' (duration: 106.822549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:28.909595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.359847ms","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-07-23T23:44:28.909658Z","caller":"traceutil/trace.go:171","msg":"trace[222550540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:873; }","duration":"107.443198ms","start":"2026-07-23T23:44:28.802199Z","end":"2026-07-23T23:44:28.909643Z","steps":["trace[222550540] 'agreement among raft nodes before linearized reading' (duration: 107.291496ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:28.911648Z","caller":"traceutil/trace.go:171","msg":"trace[535244361] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"105.573388ms","start":"2026-07-23T23:44:28.80606Z","end":"2026-07-23T23:44:28.911634Z","steps":["trace[535244361] 'process raft request' (duration: 105.312964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:28.911777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.35512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T23:44:28.91181Z","caller":"traceutil/trace.go:171","msg":"trace[1687240567] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"107.035392ms","start":"2026-07-23T23:44:28.804765Z","end":"2026-07-23T23:44:28.9118Z","steps":["trace[1687240567] 'process raft request' (duration: 106.488393ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:28.911841Z","caller":"traceutil/trace.go:171","msg":"trace[847585732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:876; }","duration":"109.459691ms","start":"2026-07-23T23:44:28.802365Z","end":"2026-07-23T23:44:28.911825Z","steps":["trace[847585732] 'agreement among raft nodes before linearized reading' (duration: 109.258568ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:30.20977Z","caller":"traceutil/trace.go:171","msg":"trace[1880122584] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"121.502135ms","start":"2026-07-23T23:44:30.088246Z","end":"2026-07-23T23:44:30.209748Z","steps":["trace[1880122584] 'process raft request' (duration: 121.27725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:38.43829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.04611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-q88d5\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-07-23T23:44:38.438373Z","caller":"traceutil/trace.go:171","msg":"trace[1953010859] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-q88d5; range_end:; response_count:1; response_revision:981; }","duration":"149.171711ms","start":"2026-07-23T23:44:38.289186Z","end":"2026-07-23T23:44:38.438358Z","steps":["trace[1953010859] 'range keys from in-memory index tree' (duration: 148.869958ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:38.737161Z","caller":"traceutil/trace.go:171","msg":"trace[2048469163] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"213.09569ms","start":"2026-07-23T23:44:38.52404Z","end":"2026-07-23T23:44:38.737135Z","steps":["trace[2048469163] 'process raft request' (duration: 116.517549ms)","trace[2048469163] 'compare' (duration: 96.476499ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:38.900333Z","caller":"traceutil/trace.go:171","msg":"trace[730413418] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"372.100793ms","start":"2026-07-23T23:44:38.52821Z","end":"2026-07-23T23:44:38.900311Z","steps":["trace[730413418] 'process raft request' (duration: 372.046122ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:38.900403Z","caller":"traceutil/trace.go:171","msg":"trace[1213580095] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"372.354787ms","start":"2026-07-23T23:44:38.528008Z","end":"2026-07-23T23:44:38.900363Z","steps":["trace[1213580095] 'process raft request' (duration: 372.107363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:38.900474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:38.528183Z","time spent":"372.217185ms","remote":"127.0.0.1:46880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:44:38.900566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:38.527989Z","time spent":"372.490359ms","remote":"127.0.0.1:46674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1628,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:44:39.240589Z","caller":"traceutil/trace.go:171","msg":"trace[740191913] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"332.382129ms","start":"2026-07-23T23:44:38.908179Z","end":"2026-07-23T23:44:39.240561Z","steps":["trace[740191913] 'process raft request' (duration: 332.194526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:39.240765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:38.908162Z","time spent":"332.520071ms","remote":"127.0.0.1:46856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:44:39.417208Z","caller":"traceutil/trace.go:171","msg":"trace[1325909829] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1021; }","duration":"449.327179ms","start":"2026-07-23T23:44:38.967864Z","end":"2026-07-23T23:44:39.417192Z","steps":["trace[1325909829] 'read index received' (duration: 272.654097ms)","trace[1325909829] 'applied index is now lower than readState.Index' (duration: 176.672322ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:39.417268Z","caller":"traceutil/trace.go:171","msg":"trace[1099367212] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"480.422348ms","start":"2026-07-23T23:44:38.936804Z","end":"2026-07-23T23:44:39.417226Z","steps":["trace[1099367212] 'process raft request' (duration: 480.246316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:39.417471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:38.936782Z","time spent":"480.59066ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:44:39.417547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.667334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-23T23:44:39.417569Z","caller":"traceutil/trace.go:171","msg":"trace[513811465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"449.743344ms","start":"2026-07-23T23:44:38.967819Z","end":"2026-07-23T23:44:39.417562Z","steps":["trace[513811465] 'agreement among raft nodes before linearized reading' (duration: 449.4661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:39.417556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.4981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-23T23:44:39.417481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.961557ms","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-07-23T23:44:39.417618Z","caller":"traceutil/trace.go:171","msg":"trace[146744174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:987; }","duration":"411.133449ms","start":"2026-07-23T23:44:39.006478Z","end":"2026-07-23T23:44:39.417611Z","steps":["trace[146744174] 'agreement among raft nodes before linearized reading' (duration: 410.926946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:39.417636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:39.006465Z","time spent":"411.165379ms","remote":"127.0.0.1:46658","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-07-23T23:44:39.417629Z","caller":"traceutil/trace.go:171","msg":"trace[1992387512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"436.590032ms","start":"2026-07-23T23:44:38.981019Z","end":"2026-07-23T23:44:39.417609Z","steps":["trace[1992387512] 'agreement among raft nodes before linearized reading' (duration: 436.408399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:39.417676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:38.981008Z","time spent":"436.654313ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T23:44:39.417768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.241975ms","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-07-23T23:44:39.418087Z","caller":"traceutil/trace.go:171","msg":"trace[388384973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"404.57824ms","start":"2026-07-23T23:44:39.013499Z","end":"2026-07-23T23:44:39.418077Z","steps":["trace[388384973] 'agreement among raft nodes before linearized reading' (duration: 403.900339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:39.418126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:39.013489Z","time spent":"404.630561ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T23:44:39.418056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:38.967801Z","time spent":"450.248404ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:44:39.418461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.526328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T23:44:39.418585Z","caller":"traceutil/trace.go:171","msg":"trace[505018764] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"450.678599ms","start":"2026-07-23T23:44:38.96789Z","end":"2026-07-23T23:44:39.418568Z","steps":["trace[505018764] 'agreement among raft nodes before linearized reading' (duration: 449.507131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:39.418631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:38.967866Z","time spent":"450.752831ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T23:44:39.677569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.354751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-23T23:44:39.677655Z","caller":"traceutil/trace.go:171","msg":"trace[880998849] transaction","detail":"{read_only:false; number_of_response:1; response_revision:988; }","duration":"256.814901ms","start":"2026-07-23T23:44:39.420829Z","end":"2026-07-23T23:44:39.677644Z","steps":["trace[880998849] 'process raft request' (duration: 79.287347ms)","trace[880998849] 'compare' (duration: 177.25547ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:39.711152Z","caller":"traceutil/trace.go:171","msg":"trace[1431059131] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"289.008325ms","start":"2026-07-23T23:44:39.422134Z","end":"2026-07-23T23:44:39.711143Z","steps":["trace[1431059131] 'process raft request' (duration: 288.925024ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:39.711459Z","caller":"traceutil/trace.go:171","msg":"trace[1226320274] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"288.65145ms","start":"2026-07-23T23:44:39.422788Z","end":"2026-07-23T23:44:39.71144Z","steps":["trace[1226320274] 'process raft request' (duration: 288.332126ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:39.711556Z","caller":"traceutil/trace.go:171","msg":"trace[1566822813] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"288.728031ms","start":"2026-07-23T23:44:39.422798Z","end":"2026-07-23T23:44:39.711526Z","steps":["trace[1566822813] 'process raft request' (duration: 288.536558ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:39.711597Z","caller":"traceutil/trace.go:171","msg":"trace[225021638] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"284.06484ms","start":"2026-07-23T23:44:39.427523Z","end":"2026-07-23T23:44:39.711588Z","steps":["trace[225021638] 'process raft request' (duration: 283.993738ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:39.711595Z","caller":"traceutil/trace.go:171","msg":"trace[467414322] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"288.611099ms","start":"2026-07-23T23:44:39.422959Z","end":"2026-07-23T23:44:39.71157Z","steps":["trace[467414322] 'process raft request' (duration: 288.479897ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:39.711678Z","caller":"traceutil/trace.go:171","msg":"trace[952916367] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"288.285114ms","start":"2026-07-23T23:44:39.423386Z","end":"2026-07-23T23:44:39.711671Z","steps":["trace[952916367] 'process raft request' (duration: 288.095321ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:40.342733Z","caller":"traceutil/trace.go:171","msg":"trace[1862535935] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"223.38081ms","start":"2026-07-23T23:44:40.119326Z","end":"2026-07-23T23:44:40.342707Z","steps":["trace[1862535935] 'process raft request' (duration: 223.239668ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:40.394704Z","caller":"traceutil/trace.go:171","msg":"trace[291085982] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"249.401577ms","start":"2026-07-23T23:44:40.145247Z","end":"2026-07-23T23:44:40.394649Z","steps":["trace[291085982] 'process raft request' (duration: 249.235654ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:45.941612Z","caller":"traceutil/trace.go:171","msg":"trace[2137029189] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"181.215812ms","start":"2026-07-23T23:44:45.760363Z","end":"2026-07-23T23:44:45.941579Z","steps":["trace[2137029189] 'process raft request' (duration: 181.06543ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:45.941648Z","caller":"traceutil/trace.go:171","msg":"trace[1154895069] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"181.244293ms","start":"2026-07-23T23:44:45.760356Z","end":"2026-07-23T23:44:45.941601Z","steps":["trace[1154895069] 'process raft request' (duration: 166.633716ms)","trace[1154895069] 'compare' (duration: 14.253842ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:45.941904Z","caller":"traceutil/trace.go:171","msg":"trace[230621355] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"181.462876ms","start":"2026-07-23T23:44:45.760401Z","end":"2026-07-23T23:44:45.941864Z","steps":["trace[230621355] 'process raft request' (duration: 181.07631ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:45.941942Z","caller":"traceutil/trace.go:171","msg":"trace[537674865] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"180.334489ms","start":"2026-07-23T23:44:45.761503Z","end":"2026-07-23T23:44:45.941838Z","steps":["trace[537674865] 'process raft request' (duration: 180.175687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:54.20762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.124551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:44:54.207914Z","caller":"traceutil/trace.go:171","msg":"trace[1084904311] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"226.065765ms","start":"2026-07-23T23:44:53.981835Z","end":"2026-07-23T23:44:54.207901Z","steps":["trace[1084904311] 'process raft request' (duration: 21.585509ms)","trace[1084904311] 'compare' (duration: 204.021ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:54.208048Z","caller":"traceutil/trace.go:171","msg":"trace[229427823] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1084; }","duration":"223.046261ms","start":"2026-07-23T23:44:53.984982Z","end":"2026-07-23T23:44:54.208028Z","steps":["trace[229427823] 'read index received' (duration: 18.531774ms)","trace[229427823] 'applied index is now lower than readState.Index' (duration: 204.512997ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:44:54.20822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.231893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-23T23:44:54.208249Z","caller":"traceutil/trace.go:171","msg":"trace[121281651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1048; }","duration":"223.274984ms","start":"2026-07-23T23:44:53.984969Z","end":"2026-07-23T23:44:54.208244Z","steps":["trace[121281651] 'agreement among raft nodes before linearized reading' (duration: 223.155222ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:54.208349Z","caller":"traceutil/trace.go:171","msg":"trace[804010238] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"225.000909ms","start":"2026-07-23T23:44:53.983117Z","end":"2026-07-23T23:44:54.208118Z","steps":["trace[804010238] 'process raft request' (duration: 224.695415ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:54.207862Z","caller":"traceutil/trace.go:171","msg":"trace[613616363] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"225.77074ms","start":"2026-07-23T23:44:53.982073Z","end":"2026-07-23T23:44:54.207844Z","steps":["trace[613616363] 'process raft request' (duration: 225.670179ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:55.265464Z","caller":"traceutil/trace.go:171","msg":"trace[303496336] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"145.431157ms","start":"2026-07-23T23:44:55.120013Z","end":"2026-07-23T23:44:55.265444Z","steps":["trace[303496336] 'read index received' (duration: 145.354006ms)","trace[303496336] 'applied index is now lower than readState.Index' (duration: 76.241µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:55.265496Z","caller":"traceutil/trace.go:171","msg":"trace[414732019] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"222.915634ms","start":"2026-07-23T23:44:55.042551Z","end":"2026-07-23T23:44:55.265467Z","steps":["trace[414732019] 'process raft request' (duration: 222.735101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:55.265597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.572069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:44:55.265628Z","caller":"traceutil/trace.go:171","msg":"trace[1041917744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1051; }","duration":"145.65137ms","start":"2026-07-23T23:44:55.119969Z","end":"2026-07-23T23:44:55.26562Z","steps":["trace[1041917744] 'agreement among raft nodes before linearized reading' (duration: 145.562058ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:55.279783Z","caller":"traceutil/trace.go:171","msg":"trace[66722345] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"149.85139ms","start":"2026-07-23T23:44:55.129894Z","end":"2026-07-23T23:44:55.279745Z","steps":["trace[66722345] 'process raft request' (duration: 149.608776ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:04.463099Z","caller":"traceutil/trace.go:171","msg":"trace[762349691] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1124; }","duration":"124.996129ms","start":"2026-07-23T23:45:04.338082Z","end":"2026-07-23T23:45:04.463078Z","steps":["trace[762349691] 'read index received' (duration: 46.27987ms)","trace[762349691] 'applied index is now lower than readState.Index' (duration: 78.675689ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:04.463291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.203572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-23T23:45:04.463317Z","caller":"traceutil/trace.go:171","msg":"trace[2058009687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1085; }","duration":"125.268962ms","start":"2026-07-23T23:45:04.338041Z","end":"2026-07-23T23:45:04.46331Z","steps":["trace[2058009687] 'agreement among raft nodes before linearized reading' (duration: 125.11616ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:04.463479Z","caller":"traceutil/trace.go:171","msg":"trace[761096397] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"129.411779ms","start":"2026-07-23T23:45:04.334013Z","end":"2026-07-23T23:45:04.463425Z","steps":["trace[761096397] 'process raft request' (duration: 110.925167ms)","trace[761096397] 'compare' (duration: 18.025336ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:04.791903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.373726ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:45:04.792065Z","caller":"traceutil/trace.go:171","msg":"trace[730174967] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1085; }","duration":"106.562628ms","start":"2026-07-23T23:45:04.685466Z","end":"2026-07-23T23:45:04.792029Z","steps":["trace[730174967] 'range keys from in-memory index tree' (duration: 106.267265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:04.792327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.051331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:45:04.792524Z","caller":"traceutil/trace.go:171","msg":"trace[189509016] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"211.577296ms","start":"2026-07-23T23:45:04.580926Z","end":"2026-07-23T23:45:04.792503Z","steps":["trace[189509016] 'process raft request' (duration: 211.500005ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:04.792782Z","caller":"traceutil/trace.go:171","msg":"trace[1341281848] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"325.89786ms","start":"2026-07-23T23:45:04.46686Z","end":"2026-07-23T23:45:04.792758Z","steps":["trace[1341281848] 'process raft request' (duration: 149.324051ms)","trace[1341281848] 'compare' (duration: 175.547284ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:04.792916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:04.466839Z","time spent":"325.997781ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:45:06.588036Z","caller":"traceutil/trace.go:171","msg":"trace[2130526600] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"117.562985ms","start":"2026-07-23T23:45:06.470446Z","end":"2026-07-23T23:45:06.588009Z","steps":["trace[2130526600] 'process raft request' (duration: 49.470376ms)","trace[2130526600] 'compare' (duration: 67.975026ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:17.223962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.876696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-23T23:45:17.224045Z","caller":"traceutil/trace.go:171","msg":"trace[1495402628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1127; }","duration":"188.001798ms","start":"2026-07-23T23:45:17.036024Z","end":"2026-07-23T23:45:17.224026Z","steps":["trace[1495402628] 'range keys from in-memory index tree' (duration: 187.714604ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:18.534764Z","caller":"traceutil/trace.go:171","msg":"trace[1045241220] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"128.273581ms","start":"2026-07-23T23:45:18.406458Z","end":"2026-07-23T23:45:18.534732Z","steps":["trace[1045241220] 'process raft request' (duration: 128.072438ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:20.684584Z","caller":"traceutil/trace.go:171","msg":"trace[1785059404] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"102.745454ms","start":"2026-07-23T23:45:20.581792Z","end":"2026-07-23T23:45:20.684538Z","steps":["trace[1785059404] 'process raft request' (duration: 78.951639ms)","trace[1785059404] 'compare' (duration: 23.48183ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:25.230363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.337785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:45:25.230461Z","caller":"traceutil/trace.go:171","msg":"trace[987896008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1153; }","duration":"117.482096ms","start":"2026-07-23T23:45:25.112963Z","end":"2026-07-23T23:45:25.230445Z","steps":["trace[987896008] 'range keys from in-memory index tree' (duration: 117.251784ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:27.214517Z","caller":"traceutil/trace.go:171","msg":"trace[341810571] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"357.947684ms","start":"2026-07-23T23:45:26.856507Z","end":"2026-07-23T23:45:27.214455Z","steps":["trace[341810571] 'process raft request' (duration: 357.68208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:27.214766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:26.856491Z","time spent":"358.131636ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:45:27.217088Z","caller":"traceutil/trace.go:171","msg":"trace[1199668605] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1204; }","duration":"104.523935ms","start":"2026-07-23T23:45:27.11254Z","end":"2026-07-23T23:45:27.217064Z","steps":["trace[1199668605] 'read index received' (duration: 101.652039ms)","trace[1199668605] 'applied index is now lower than readState.Index' (duration: 2.870886ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:45:27.217189Z","caller":"traceutil/trace.go:171","msg":"trace[952413493] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"219.639262ms","start":"2026-07-23T23:45:26.997519Z","end":"2026-07-23T23:45:27.217158Z","steps":["trace[952413493] 'process raft request' (duration: 219.415368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:27.217228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.651637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:45:27.217263Z","caller":"traceutil/trace.go:171","msg":"trace[1630931598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1160; }","duration":"104.763768ms","start":"2026-07-23T23:45:27.112489Z","end":"2026-07-23T23:45:27.217253Z","steps":["trace[1630931598] 'agreement among raft nodes before linearized reading' (duration: 104.667447ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:28.912564Z","caller":"traceutil/trace.go:171","msg":"trace[1253287430] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"108.304737ms","start":"2026-07-23T23:45:28.804234Z","end":"2026-07-23T23:45:28.912539Z","steps":["trace[1253287430] 'process raft request' (duration: 108.187786ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:29.373817Z","caller":"traceutil/trace.go:171","msg":"trace[778456575] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"152.39264ms","start":"2026-07-23T23:45:29.221389Z","end":"2026-07-23T23:45:29.373781Z","steps":["trace[778456575] 'process raft request' (duration: 152.208777ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:29.511565Z","caller":"traceutil/trace.go:171","msg":"trace[1568956529] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"289.160648ms","start":"2026-07-23T23:45:29.222365Z","end":"2026-07-23T23:45:29.511526Z","steps":["trace[1568956529] 'process raft request' (duration: 214.858146ms)","trace[1568956529] 'compare' (duration: 74.120019ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:45:29.511492Z","caller":"traceutil/trace.go:171","msg":"trace[1400105619] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1210; }","duration":"286.056269ms","start":"2026-07-23T23:45:29.225403Z","end":"2026-07-23T23:45:29.51146Z","steps":["trace[1400105619] 'read index received' (duration: 148.228289ms)","trace[1400105619] 'applied index is now lower than readState.Index' (duration: 137.82662ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:29.511625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.151661ms","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-07-23T23:45:29.51179Z","caller":"traceutil/trace.go:171","msg":"trace[213933712] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1166; }","duration":"286.358873ms","start":"2026-07-23T23:45:29.225378Z","end":"2026-07-23T23:45:29.511737Z","steps":["trace[213933712] 'agreement among raft nodes before linearized reading' (duration: 286.12453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:31.490548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.611391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:45:31.490832Z","caller":"traceutil/trace.go:171","msg":"trace[1089229208] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"844.474835ms","start":"2026-07-23T23:45:30.64632Z","end":"2026-07-23T23:45:31.490795Z","steps":["trace[1089229208] 'process raft request' (duration: 365.50595ms)","trace[1089229208] 'compare' (duration: 478.025694ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:31.491044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:30.646296Z","time spent":"844.702048ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:45:31.728064Z","caller":"traceutil/trace.go:171","msg":"trace[673187986] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"348.264114ms","start":"2026-07-23T23:45:31.379777Z","end":"2026-07-23T23:45:31.728041Z","steps":["trace[673187986] 'process raft request' (duration: 348.218994ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:31.728138Z","caller":"traceutil/trace.go:171","msg":"trace[813920371] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"995.550945ms","start":"2026-07-23T23:45:30.732562Z","end":"2026-07-23T23:45:31.728113Z","steps":["trace[813920371] 'process raft request' (duration: 938.772195ms)","trace[813920371] 'compare' (duration: 56.512707ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:31.72818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:31.379727Z","time spent":"348.393405ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:45:31.728196Z","caller":"traceutil/trace.go:171","msg":"trace[914588707] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1215; }","duration":"812.492221ms","start":"2026-07-23T23:45:30.91566Z","end":"2026-07-23T23:45:31.728152Z","steps":["trace[914588707] 'read index received' (duration: 96.210729ms)","trace[914588707] 'applied index is now lower than readState.Index' (duration: 716.279572ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:31.728257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:30.732531Z","time spent":"995.668986ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:45:31.728416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.004655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:45:31.72846Z","caller":"traceutil/trace.go:171","msg":"trace[631459804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1171; }","duration":"616.085716ms","start":"2026-07-23T23:45:31.11236Z","end":"2026-07-23T23:45:31.728446Z","steps":["trace[631459804] 'agreement among raft nodes before linearized reading' (duration: 616.025185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:31.728497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:31.11234Z","time spent":"616.150847ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:45:31.728506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.831955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-23T23:45:31.728526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.481177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-23T23:45:31.72855Z","caller":"traceutil/trace.go:171","msg":"trace[1970256366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1171; }","duration":"168.530447ms","start":"2026-07-23T23:45:31.560013Z","end":"2026-07-23T23:45:31.728543Z","steps":["trace[1970256366] 'agreement among raft nodes before linearized reading' (duration: 168.436326ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:31.728553Z","caller":"traceutil/trace.go:171","msg":"trace[971058627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1171; }","duration":"812.939676ms","start":"2026-07-23T23:45:30.915599Z","end":"2026-07-23T23:45:31.728539Z","steps":["trace[971058627] 'agreement among raft nodes before linearized reading' (duration: 812.713784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:31.728592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:30.915575Z","time spent":"813.008017ms","remote":"127.0.0.1:46658","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-07-23T23:45:31.728655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.230499ms","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-07-23T23:45:31.728679Z","caller":"traceutil/trace.go:171","msg":"trace[1673748313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1171; }","duration":"213.288979ms","start":"2026-07-23T23:45:31.515383Z","end":"2026-07-23T23:45:31.728672Z","steps":["trace[1673748313] 'agreement among raft nodes before linearized reading' (duration: 213.232718ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:31.934914Z","caller":"traceutil/trace.go:171","msg":"trace[158707738] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"201.057309ms","start":"2026-07-23T23:45:31.73383Z","end":"2026-07-23T23:45:31.934887Z","steps":["trace[158707738] 'process raft request' (duration: 201.006688ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:31.934935Z","caller":"traceutil/trace.go:171","msg":"trace[1413332322] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"201.180631ms","start":"2026-07-23T23:45:31.733715Z","end":"2026-07-23T23:45:31.934896Z","steps":["trace[1413332322] 'process raft request' (duration: 173.404189ms)","trace[1413332322] 'compare' (duration: 27.59404ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:46:21.463874Z","caller":"traceutil/trace.go:171","msg":"trace[1984282825] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"116.90068ms","start":"2026-07-23T23:46:21.346956Z","end":"2026-07-23T23:46:21.463857Z","steps":["trace[1984282825] 'process raft request' (duration: 116.760158ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:22.069126Z","caller":"traceutil/trace.go:171","msg":"trace[2109912127] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"170.396369ms","start":"2026-07-23T23:46:21.898702Z","end":"2026-07-23T23:46:22.069098Z","steps":["trace[2109912127] 'process raft request' (duration: 170.218307ms)"],"step_count":1}