{"level":"warn","ts":"2026-02-05T11:11:33.76107Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T11:11:33.761154Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.249:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.249:2380","--initial-cluster=instance=https://199.19.213.249:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.249:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.249: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-02-05T11:11:33.761215Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T11:11:33.761223Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.249:2380"]} {"level":"info","ts":"2026-02-05T11:11:33.761247Z","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-02-05T11:11:33.761768Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"]} {"level":"info","ts":"2026-02-05T11:11:33.761834Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.249:2380"],"listen-peer-urls":["https://199.19.213.249:2380"],"advertise-client-urls":["https://199.19.213.249:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.249: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-02-05T11:11:33.766026Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.011178ms"} {"level":"info","ts":"2026-02-05T11:11:33.773901Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5835264b86215850","cluster-id":"4e10f3807bdbbd9b"} {"level":"info","ts":"2026-02-05T11:11:33.773947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T11:11:33.773965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became follower at term 0"} {"level":"info","ts":"2026-02-05T11:11:33.773972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5835264b86215850 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T11:11:33.773978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became follower at term 1"} {"level":"info","ts":"2026-02-05T11:11:33.774003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=(6356028554918058064)"} {"level":"warn","ts":"2026-02-05T11:11:33.790562Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T11:11:33.793897Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T11:11:33.79526Z","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-02-05T11:11:33.797395Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5835264b86215850","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T11:11:33.799344Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5835264b86215850","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T11:11:33.799454Z","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-02-05T11:11:33.799516Z","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-02-05T11:11:33.799541Z","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-02-05T11:11:33.799707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=(6356028554918058064)"} {"level":"info","ts":"2026-02-05T11:11:33.799813Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4e10f3807bdbbd9b","local-member-id":"5835264b86215850","added-peer-id":"5835264b86215850","added-peer-peer-urls":["https://199.19.213.249:2380"]} {"level":"info","ts":"2026-02-05T11:11:33.799972Z","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-02-05T11:11:33.800066Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.249:2380"} {"level":"info","ts":"2026-02-05T11:11:33.800179Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.249:2380"} {"level":"info","ts":"2026-02-05T11:11:33.801213Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5835264b86215850","initial-advertise-peer-urls":["https://199.19.213.249:2380"],"listen-peer-urls":["https://199.19.213.249:2380"],"advertise-client-urls":["https://199.19.213.249:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T11:11:33.801256Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T11:11:34.674141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T11:11:34.674198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T11:11:34.674235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 received MsgPreVoteResp from 5835264b86215850 at term 1"} {"level":"info","ts":"2026-02-05T11:11:34.674262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became candidate at term 2"} {"level":"info","ts":"2026-02-05T11:11:34.674283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 received MsgVoteResp from 5835264b86215850 at term 2"} {"level":"info","ts":"2026-02-05T11:11:34.674299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became leader at term 2"} {"level":"info","ts":"2026-02-05T11:11:34.674312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5835264b86215850 elected leader 5835264b86215850 at term 2"} {"level":"info","ts":"2026-02-05T11:11:34.675438Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5835264b86215850","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.249:2379]}","request-path":"/0/members/5835264b86215850/attributes","cluster-id":"4e10f3807bdbbd9b","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T11:11:34.675479Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T11:11:34.675896Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T11:11:34.676332Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T11:11:34.676628Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T11:11:34.676643Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T11:11:34.677993Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.249:2379"} {"level":"info","ts":"2026-02-05T11:11:34.678386Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4e10f3807bdbbd9b","local-member-id":"5835264b86215850","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T11:11:34.678439Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T11:11:34.678462Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T11:11:34.679821Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T11:11:47.760745Z","caller":"traceutil/trace.go:171","msg":"trace[1556614482] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"229.364977ms","start":"2026-02-05T11:11:47.53132Z","end":"2026-02-05T11:11:47.760685Z","steps":["trace[1556614482] 'read index received' (duration: 229.155274ms)","trace[1556614482] 'applied index is now lower than readState.Index' (duration: 208.833µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:11:47.762221Z","caller":"traceutil/trace.go:171","msg":"trace[1510609096] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"469.53791ms","start":"2026-02-05T11:11:47.29263Z","end":"2026-02-05T11:11:47.762168Z","steps":["trace[1510609096] 'process raft request' (duration: 467.902466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:11:47.762942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.603604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.249\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-05T11:11:47.763011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:11:47.292621Z","time spent":"469.725381ms","remote":"127.0.0.1:46202","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-02-05T11:11:47.763149Z","caller":"traceutil/trace.go:171","msg":"trace[13349734] range","detail":"{range_begin:/registry/masterleases/199.19.213.249; range_end:; response_count:1; response_revision:408; }","duration":"231.869925ms","start":"2026-02-05T11:11:47.531258Z","end":"2026-02-05T11:11:47.763128Z","steps":["trace[13349734] 'agreement among raft nodes before linearized reading' (duration: 231.572983ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:11:48.311817Z","caller":"traceutil/trace.go:171","msg":"trace[1096642211] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"110.043577ms","start":"2026-02-05T11:11:48.20176Z","end":"2026-02-05T11:11:48.311803Z","steps":["trace[1096642211] 'process raft request' (duration: 109.796295ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:11:48.471042Z","caller":"traceutil/trace.go:171","msg":"trace[265341702] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"153.289061ms","start":"2026-02-05T11:11:48.31773Z","end":"2026-02-05T11:11:48.471019Z","steps":["trace[265341702] 'process raft request' (duration: 153.021389ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:11:48.473179Z","caller":"traceutil/trace.go:171","msg":"trace[1688213976] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"154.271759ms","start":"2026-02-05T11:11:48.318897Z","end":"2026-02-05T11:11:48.473169Z","steps":["trace[1688213976] 'process raft request' (duration: 154.201168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:11:53.967621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.417704ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-05T11:11:53.967718Z","caller":"traceutil/trace.go:171","msg":"trace[1687893807] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:467; }","duration":"276.902804ms","start":"2026-02-05T11:11:53.690798Z","end":"2026-02-05T11:11:53.967701Z","steps":["trace[1687893807] 'read index received' (duration: 17.39µs)","trace[1687893807] 'applied index is now lower than readState.Index' (duration: 276.884144ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:11:53.967892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.083275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:11:53.967928Z","caller":"traceutil/trace.go:171","msg":"trace[30040565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:454; }","duration":"277.163896ms","start":"2026-02-05T11:11:53.690754Z","end":"2026-02-05T11:11:53.967918Z","steps":["trace[30040565] 'agreement among raft nodes before linearized reading' (duration: 277.046016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:11:53.967942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.823363ms","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-02-05T11:11:53.967988Z","caller":"traceutil/trace.go:171","msg":"trace[962439147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:454; }","duration":"176.922004ms","start":"2026-02-05T11:11:53.791055Z","end":"2026-02-05T11:11:53.967977Z","steps":["trace[962439147] 'agreement among raft nodes before linearized reading' (duration: 176.805823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:07.869692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.141961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T11:12:07.869773Z","caller":"traceutil/trace.go:171","msg":"trace[534889140] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"514.409034ms","start":"2026-02-05T11:12:07.355348Z","end":"2026-02-05T11:12:07.869757Z","steps":["trace[534889140] 'process raft request' (duration: 72.135202ms)","trace[534889140] 'compare' (duration: 442.01912ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:07.869817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:07.355338Z","time spent":"514.458515ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T11:12:07.888115Z","caller":"traceutil/trace.go:171","msg":"trace[1035562425] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:546; }","duration":"355.949393ms","start":"2026-02-05T11:12:07.532145Z","end":"2026-02-05T11:12:07.888095Z","steps":["trace[1035562425] 'read index received' (duration: 355.903172ms)","trace[1035562425] 'applied index is now lower than readState.Index' (duration: 45.471µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:07.888206Z","caller":"traceutil/trace.go:171","msg":"trace[2059139475] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"495.352845ms","start":"2026-02-05T11:12:07.392845Z","end":"2026-02-05T11:12:07.888198Z","steps":["trace[2059139475] 'process raft request' (duration: 495.103932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:07.888279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:07.39279Z","time spent":"495.440395ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T11:12:07.888302Z","caller":"traceutil/trace.go:171","msg":"trace[1582105180] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"493.516122ms","start":"2026-02-05T11:12:07.394765Z","end":"2026-02-05T11:12:07.888282Z","steps":["trace[1582105180] 'process raft request' (duration: 493.3025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:07.888402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:07.394761Z","time spent":"493.586162ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T11:12:07.888512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.360236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.249\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T11:12:07.888536Z","caller":"traceutil/trace.go:171","msg":"trace[1442355657] range","detail":"{range_begin:/registry/masterleases/199.19.213.249; range_end:; response_count:1; response_revision:532; }","duration":"356.422816ms","start":"2026-02-05T11:12:07.532106Z","end":"2026-02-05T11:12:07.888529Z","steps":["trace[1442355657] 'agreement among raft nodes before linearized reading' (duration: 356.344395ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:07.888544Z","caller":"traceutil/trace.go:171","msg":"trace[906282136] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"493.811634ms","start":"2026-02-05T11:12:07.394725Z","end":"2026-02-05T11:12:07.888537Z","steps":["trace[906282136] 'process raft request' (duration: 493.317159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:07.888555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:07.532088Z","time spent":"356.462686ms","remote":"127.0.0.1:45984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.249\" "} {"level":"warn","ts":"2026-02-05T11:12:07.888577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:07.394705Z","time spent":"493.856645ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T11:12:08.139429Z","caller":"traceutil/trace.go:171","msg":"trace[1710457470] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"401.557816ms","start":"2026-02-05T11:12:07.737849Z","end":"2026-02-05T11:12:08.139407Z","steps":["trace[1710457470] 'process raft request' (duration: 401.518376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.139659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:07.737834Z","time spent":"401.738977ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T11:12:08.140028Z","caller":"traceutil/trace.go:171","msg":"trace[1540550272] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"604.299701ms","start":"2026-02-05T11:12:07.535716Z","end":"2026-02-05T11:12:08.140016Z","steps":["trace[1540550272] 'process raft request' (duration: 603.512986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.140224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:07.535552Z","time spent":"604.640533ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T11:12:08.140671Z","caller":"traceutil/trace.go:171","msg":"trace[412574428] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:550; }","duration":"252.491398ms","start":"2026-02-05T11:12:07.888168Z","end":"2026-02-05T11:12:08.14066Z","steps":["trace[412574428] 'read index received' (duration: 252.486918ms)","trace[412574428] 'applied index is now lower than readState.Index' (duration: 3.56µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:08.140985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.806968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-77j85\" ","response":"range_response_count:1 size:4639"} {"level":"info","ts":"2026-02-05T11:12:08.141066Z","caller":"traceutil/trace.go:171","msg":"trace[792966927] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-77j85; range_end:; response_count:1; response_revision:534; }","duration":"496.941099ms","start":"2026-02-05T11:12:07.644114Z","end":"2026-02-05T11:12:08.141055Z","steps":["trace[792966927] 'agreement among raft nodes before linearized reading' (duration: 496.709727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.141149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:07.644102Z","time spent":"497.035189ms","remote":"127.0.0.1:46136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4661,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-77j85\" "} {"level":"warn","ts":"2026-02-05T11:12:08.21656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:07.891473Z","time spent":"325.08435ms","remote":"127.0.0.1:45984","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T11:12:08.21711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.588682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T11:12:08.217186Z","caller":"traceutil/trace.go:171","msg":"trace[143708256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"172.712792ms","start":"2026-02-05T11:12:08.044454Z","end":"2026-02-05T11:12:08.217166Z","steps":["trace[143708256] 'agreement among raft nodes before linearized reading' (duration: 172.440681ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.217335Z","caller":"traceutil/trace.go:171","msg":"trace[1012739388] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"277.584285ms","start":"2026-02-05T11:12:07.939731Z","end":"2026-02-05T11:12:08.217315Z","steps":["trace[1012739388] 'process raft request' (duration: 276.145526ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.217567Z","caller":"traceutil/trace.go:171","msg":"trace[995064313] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"276.867391ms","start":"2026-02-05T11:12:07.940663Z","end":"2026-02-05T11:12:08.217531Z","steps":["trace[995064313] 'process raft request' (duration: 275.313111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.217649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.264159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:12:08.217698Z","caller":"traceutil/trace.go:171","msg":"trace[225550883] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:539; }","duration":"269.36318ms","start":"2026-02-05T11:12:07.94832Z","end":"2026-02-05T11:12:08.217683Z","steps":["trace[225550883] 'agreement among raft nodes before linearized reading' (duration: 268.569955ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.218014Z","caller":"traceutil/trace.go:171","msg":"trace[280027737] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"274.040222ms","start":"2026-02-05T11:12:07.943961Z","end":"2026-02-05T11:12:08.218001Z","steps":["trace[280027737] 'process raft request' (duration: 272.045409ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.218333Z","caller":"traceutil/trace.go:171","msg":"trace[1016156385] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"269.892923ms","start":"2026-02-05T11:12:07.948397Z","end":"2026-02-05T11:12:08.21829Z","steps":["trace[1016156385] 'process raft request' (duration: 267.664638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.354017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.470266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T11:12:08.354324Z","caller":"traceutil/trace.go:171","msg":"trace[1012984391] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"203.486592ms","start":"2026-02-05T11:12:08.150821Z","end":"2026-02-05T11:12:08.354307Z","steps":["trace[1012984391] 'process raft request' (duration: 97.660003ms)","trace[1012984391] 'compare' (duration: 105.387516ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:08.378648Z","caller":"traceutil/trace.go:171","msg":"trace[976080241] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:556; }","duration":"149.358245ms","start":"2026-02-05T11:12:08.229278Z","end":"2026-02-05T11:12:08.378636Z","steps":["trace[976080241] 'read index received' (duration: 19.206301ms)","trace[976080241] 'applied index is now lower than readState.Index' (duration: 130.151244ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:08.378937Z","caller":"traceutil/trace.go:171","msg":"trace[856276579] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"150.2377ms","start":"2026-02-05T11:12:08.228691Z","end":"2026-02-05T11:12:08.378929Z","steps":["trace[856276579] 'process raft request' (duration: 149.758877ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.379254Z","caller":"traceutil/trace.go:171","msg":"trace[97548499] transaction","detail":"{read_only:false; number_of_response:0; response_revision:541; }","duration":"150.453142ms","start":"2026-02-05T11:12:08.228793Z","end":"2026-02-05T11:12:08.379247Z","steps":["trace[97548499] 'process raft request' (duration: 149.725587ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.379793Z","caller":"traceutil/trace.go:171","msg":"trace[1831664639] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"150.562872ms","start":"2026-02-05T11:12:08.229221Z","end":"2026-02-05T11:12:08.379784Z","steps":["trace[1831664639] 'process raft request' (duration: 149.324394ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.38002Z","caller":"traceutil/trace.go:171","msg":"trace[1781433374] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"150.546712ms","start":"2026-02-05T11:12:08.229466Z","end":"2026-02-05T11:12:08.380013Z","steps":["trace[1781433374] 'process raft request' (duration: 149.115212ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.380214Z","caller":"traceutil/trace.go:171","msg":"trace[1082582006] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"150.481002ms","start":"2026-02-05T11:12:08.229725Z","end":"2026-02-05T11:12:08.380206Z","steps":["trace[1082582006] 'process raft request' (duration: 148.883992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.380439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.143156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:12:08.380532Z","caller":"traceutil/trace.go:171","msg":"trace[937623837] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:0; response_revision:544; }","duration":"151.262507ms","start":"2026-02-05T11:12:08.229262Z","end":"2026-02-05T11:12:08.380524Z","steps":["trace[937623837] 'agreement among raft nodes before linearized reading' (duration: 151.140336ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.525587Z","caller":"traceutil/trace.go:171","msg":"trace[898386057] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"132.990092ms","start":"2026-02-05T11:12:08.392582Z","end":"2026-02-05T11:12:08.525572Z","steps":["trace[898386057] 'process raft request' (duration: 132.891722ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.525785Z","caller":"traceutil/trace.go:171","msg":"trace[612542473] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:563; }","duration":"132.408888ms","start":"2026-02-05T11:12:08.393369Z","end":"2026-02-05T11:12:08.525778Z","steps":["trace[612542473] 'read index received' (duration: 132.404418ms)","trace[612542473] 'applied index is now lower than readState.Index' (duration: 3.67µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:08.525849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.460749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-05T11:12:08.526083Z","caller":"traceutil/trace.go:171","msg":"trace[144460291] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:545; }","duration":"132.7191ms","start":"2026-02-05T11:12:08.393357Z","end":"2026-02-05T11:12:08.526076Z","steps":["trace[144460291] 'agreement among raft nodes before linearized reading' (duration: 132.443879ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.698226Z","caller":"traceutil/trace.go:171","msg":"trace[839045376] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"304.781259ms","start":"2026-02-05T11:12:08.3934Z","end":"2026-02-05T11:12:08.698181Z","steps":["trace[839045376] 'process raft request' (duration: 235.434908ms)","trace[839045376] 'compare' (duration: 69.15384ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:08.698392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:08.393395Z","time spent":"304.93508ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T11:12:08.698789Z","caller":"traceutil/trace.go:171","msg":"trace[147036957] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"300.665011ms","start":"2026-02-05T11:12:08.39811Z","end":"2026-02-05T11:12:08.698775Z","steps":["trace[147036957] 'process raft request' (duration: 300.028977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.698977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:08.398102Z","time spent":"300.768182ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T11:12:08.699228Z","caller":"traceutil/trace.go:171","msg":"trace[1329543824] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"163.64584ms","start":"2026-02-05T11:12:08.535569Z","end":"2026-02-05T11:12:08.699215Z","steps":["trace[1329543824] 'process raft request' (duration: 163.025986ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.699509Z","caller":"traceutil/trace.go:171","msg":"trace[1110169017] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:563; }","duration":"173.480728ms","start":"2026-02-05T11:12:08.526016Z","end":"2026-02-05T11:12:08.699497Z","steps":["trace[1110169017] 'read index received' (duration: 102.828388ms)","trace[1110169017] 'applied index is now lower than readState.Index' (duration: 70.65123ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:08.6998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.148522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:12:08.700378Z","caller":"traceutil/trace.go:171","msg":"trace[608418383] range","detail":"{range_begin:/registry/clusterroles/local-path-provisioner; range_end:; response_count:0; response_revision:548; }","duration":"305.733915ms","start":"2026-02-05T11:12:08.394634Z","end":"2026-02-05T11:12:08.700368Z","steps":["trace[608418383] 'agreement among raft nodes before linearized reading' (duration: 305.142222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.700447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:08.394629Z","time spent":"305.808886ms","remote":"127.0.0.1:46274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/local-path-provisioner\" "} {"level":"warn","ts":"2026-02-05T11:12:08.700912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.054174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:12:08.702103Z","caller":"traceutil/trace.go:171","msg":"trace[752089586] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:548; }","duration":"165.258081ms","start":"2026-02-05T11:12:08.536833Z","end":"2026-02-05T11:12:08.702092Z","steps":["trace[752089586] 'agreement among raft nodes before linearized reading' (duration: 164.061814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.7052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.545764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T11:12:08.707168Z","caller":"traceutil/trace.go:171","msg":"trace[247394033] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:548; }","duration":"179.530459ms","start":"2026-02-05T11:12:08.527627Z","end":"2026-02-05T11:12:08.707157Z","steps":["trace[247394033] 'agreement among raft nodes before linearized reading' (duration: 173.178706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.704225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.001694ms","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-02-05T11:12:08.707456Z","caller":"traceutil/trace.go:171","msg":"trace[2021126074] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:548; }","duration":"226.261217ms","start":"2026-02-05T11:12:08.481186Z","end":"2026-02-05T11:12:08.707447Z","steps":["trace[2021126074] 'agreement among raft nodes before linearized reading' (duration: 219.463341ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.835782Z","caller":"traceutil/trace.go:171","msg":"trace[657158170] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"125.010169ms","start":"2026-02-05T11:12:08.710758Z","end":"2026-02-05T11:12:08.835768Z","steps":["trace[657158170] 'process raft request' (duration: 124.649137ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.835791Z","caller":"traceutil/trace.go:171","msg":"trace[11949126] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:567; }","duration":"122.830034ms","start":"2026-02-05T11:12:08.712931Z","end":"2026-02-05T11:12:08.835761Z","steps":["trace[11949126] 'read index received' (duration: 62.677185ms)","trace[11949126] 'applied index is now lower than readState.Index' (duration: 60.151879ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:08.835851Z","caller":"traceutil/trace.go:171","msg":"trace[1938021913] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"123.280587ms","start":"2026-02-05T11:12:08.712481Z","end":"2026-02-05T11:12:08.835761Z","steps":["trace[1938021913] 'process raft request' (duration: 123.075186ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.836065Z","caller":"traceutil/trace.go:171","msg":"trace[1076401922] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"120.297757ms","start":"2026-02-05T11:12:08.715758Z","end":"2026-02-05T11:12:08.836055Z","steps":["trace[1076401922] 'process raft request' (duration: 119.924314ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.836097Z","caller":"traceutil/trace.go:171","msg":"trace[1363367656] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"120.001904ms","start":"2026-02-05T11:12:08.716088Z","end":"2026-02-05T11:12:08.83609Z","steps":["trace[1363367656] 'process raft request' (duration: 119.635252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.836228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.277727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:12:08.838306Z","caller":"traceutil/trace.go:171","msg":"trace[1573178245] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:553; }","duration":"125.403331ms","start":"2026-02-05T11:12:08.712888Z","end":"2026-02-05T11:12:08.838292Z","steps":["trace[1573178245] 'agreement among raft nodes before linearized reading' (duration: 123.224526ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:08.91286Z","caller":"traceutil/trace.go:171","msg":"trace[325845034] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"126.831451ms","start":"2026-02-05T11:12:08.786005Z","end":"2026-02-05T11:12:08.912837Z","steps":["trace[325845034] 'process raft request' (duration: 126.425528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.913453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.329291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T11:12:08.913502Z","caller":"traceutil/trace.go:171","msg":"trace[825932236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:554; }","duration":"200.377231ms","start":"2026-02-05T11:12:08.713112Z","end":"2026-02-05T11:12:08.913489Z","steps":["trace[825932236] 'agreement among raft nodes before linearized reading' (duration: 200.244659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.913949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.985218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-05T11:12:08.913991Z","caller":"traceutil/trace.go:171","msg":"trace[1622990258] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:554; }","duration":"200.040508ms","start":"2026-02-05T11:12:08.713941Z","end":"2026-02-05T11:12:08.913981Z","steps":["trace[1622990258] 'agreement among raft nodes before linearized reading' (duration: 199.965398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:08.914726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.78237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:12:08.9148Z","caller":"traceutil/trace.go:171","msg":"trace[1072147149] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:554; }","duration":"181.366361ms","start":"2026-02-05T11:12:08.733394Z","end":"2026-02-05T11:12:08.914761Z","steps":["trace[1072147149] 'agreement among raft nodes before linearized reading' (duration: 179.78782ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:09.191233Z","caller":"traceutil/trace.go:171","msg":"trace[551714159] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"345.196757ms","start":"2026-02-05T11:12:08.846015Z","end":"2026-02-05T11:12:09.191212Z","steps":["trace[551714159] 'process raft request' (duration: 344.973795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:09.191638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:08.845998Z","time spent":"345.485778ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T11:12:09.191848Z","caller":"traceutil/trace.go:171","msg":"trace[559457479] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:573; }","duration":"279.254419ms","start":"2026-02-05T11:12:08.912571Z","end":"2026-02-05T11:12:09.191825Z","steps":["trace[559457479] 'read index received' (duration: 279.246499ms)","trace[559457479] 'applied index is now lower than readState.Index' (duration: 6.68µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:09.192095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.660541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:12:09.192528Z","caller":"traceutil/trace.go:171","msg":"trace[667802722] range","detail":"{range_begin:/registry/resourcequotas/local-path-storage/; range_end:/registry/resourcequotas/local-path-storage0; response_count:0; response_revision:555; }","duration":"336.133634ms","start":"2026-02-05T11:12:08.856378Z","end":"2026-02-05T11:12:09.192512Z","steps":["trace[667802722] 'agreement among raft nodes before linearized reading' (duration: 335.55211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:09.192622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:08.856367Z","time spent":"336.207284ms","remote":"127.0.0.1:46036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" "} {"level":"warn","ts":"2026-02-05T11:12:09.462383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.421794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T11:12:09.462777Z","caller":"traceutil/trace.go:171","msg":"trace[871320058] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"541.359767ms","start":"2026-02-05T11:12:08.921388Z","end":"2026-02-05T11:12:09.462748Z","steps":["trace[871320058] 'process raft request' (duration: 402.444869ms)","trace[871320058] 'compare' (duration: 138.252453ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:09.46298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:08.921373Z","time spent":"541.475538ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T11:12:09.463378Z","caller":"traceutil/trace.go:171","msg":"trace[1390554395] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:573; }","duration":"271.446922ms","start":"2026-02-05T11:12:09.191912Z","end":"2026-02-05T11:12:09.463359Z","steps":["trace[1390554395] 'read index received' (duration: 132.052821ms)","trace[1390554395] 'applied index is now lower than readState.Index' (duration: 139.393201ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:09.46402Z","caller":"traceutil/trace.go:171","msg":"trace[275577281] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"328.691788ms","start":"2026-02-05T11:12:09.135315Z","end":"2026-02-05T11:12:09.464006Z","steps":["trace[275577281] 'process raft request' (duration: 327.964293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:09.464092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:09.135287Z","time spent":"328.774078ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T11:12:09.464364Z","caller":"traceutil/trace.go:171","msg":"trace[2126094425] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"542.684255ms","start":"2026-02-05T11:12:08.921668Z","end":"2026-02-05T11:12:09.464352Z","steps":["trace[2126094425] 'process raft request' (duration: 541.315706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:09.464424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:08.921648Z","time spent":"542.742206ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T11:12:09.464766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.065934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:12:09.464815Z","caller":"traceutil/trace.go:171","msg":"trace[223426243] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:558; }","duration":"532.182834ms","start":"2026-02-05T11:12:08.932621Z","end":"2026-02-05T11:12:09.464804Z","steps":["trace[223426243] 'agreement among raft nodes before linearized reading' (duration: 532.066273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:09.464847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:08.932614Z","time spent":"532.225224ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-05T11:12:09.524598Z","caller":"traceutil/trace.go:171","msg":"trace[1214232379] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"321.764831ms","start":"2026-02-05T11:12:09.202813Z","end":"2026-02-05T11:12:09.524578Z","steps":["trace[1214232379] 'process raft request' (duration: 321.694131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:09.524912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:09.202796Z","time spent":"322.012583ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T11:12:09.525262Z","caller":"traceutil/trace.go:171","msg":"trace[266985359] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"329.061541ms","start":"2026-02-05T11:12:09.196192Z","end":"2026-02-05T11:12:09.525253Z","steps":["trace[266985359] 'process raft request' (duration: 328.220176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:09.525359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:09.196177Z","time spent":"329.157982ms","remote":"127.0.0.1:46082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":389,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T11:12:09.525315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.047866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-05T11:12:09.525503Z","caller":"traceutil/trace.go:171","msg":"trace[1898760723] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:560; }","duration":"192.286288ms","start":"2026-02-05T11:12:09.333192Z","end":"2026-02-05T11:12:09.525478Z","steps":["trace[1898760723] 'agreement among raft nodes before linearized reading' (duration: 191.783295ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:09.80878Z","caller":"traceutil/trace.go:171","msg":"trace[645165637] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"333.402669ms","start":"2026-02-05T11:12:09.475357Z","end":"2026-02-05T11:12:09.808759Z","steps":["trace[645165637] 'process raft request' (duration: 333.249628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:09.808956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:09.475341Z","time spent":"333.517061ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T11:12:09.829611Z","caller":"traceutil/trace.go:171","msg":"trace[902476552] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"288.671298ms","start":"2026-02-05T11:12:09.540917Z","end":"2026-02-05T11:12:09.829589Z","steps":["trace[902476552] 'process raft request' (duration: 288.420375ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:09.829667Z","caller":"traceutil/trace.go:171","msg":"trace[1523217358] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:578; }","duration":"288.405396ms","start":"2026-02-05T11:12:09.541239Z","end":"2026-02-05T11:12:09.829645Z","steps":["trace[1523217358] 'read index received' (duration: 267.302124ms)","trace[1523217358] 'applied index is now lower than readState.Index' (duration: 21.102382ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:09.829813Z","caller":"traceutil/trace.go:171","msg":"trace[2048452781] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"288.755858ms","start":"2026-02-05T11:12:09.54105Z","end":"2026-02-05T11:12:09.829806Z","steps":["trace[2048452781] 'process raft request' (duration: 288.435806ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:09.829901Z","caller":"traceutil/trace.go:171","msg":"trace[1936934743] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"288.910939ms","start":"2026-02-05T11:12:09.540982Z","end":"2026-02-05T11:12:09.829893Z","steps":["trace[1936934743] 'process raft request' (duration: 288.461836ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:09.830028Z","caller":"traceutil/trace.go:171","msg":"trace[5466786] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"287.154738ms","start":"2026-02-05T11:12:09.542858Z","end":"2026-02-05T11:12:09.830012Z","steps":["trace[5466786] 'process raft request' (duration: 286.662925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:09.830215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.96195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-05T11:12:09.830241Z","caller":"traceutil/trace.go:171","msg":"trace[1321216222] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:565; }","duration":"289.01363ms","start":"2026-02-05T11:12:09.541219Z","end":"2026-02-05T11:12:09.830233Z","steps":["trace[1321216222] 'agreement among raft nodes before linearized reading' (duration: 288.90925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:09.859348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.478437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-05T11:12:09.859486Z","caller":"traceutil/trace.go:171","msg":"trace[1042848381] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:566; }","duration":"125.668738ms","start":"2026-02-05T11:12:09.733799Z","end":"2026-02-05T11:12:09.859467Z","steps":["trace[1042848381] 'agreement among raft nodes before linearized reading' (duration: 125.387546ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:11.403683Z","caller":"traceutil/trace.go:171","msg":"trace[1510633137] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"428.998151ms","start":"2026-02-05T11:12:10.974669Z","end":"2026-02-05T11:12:11.403667Z","steps":["trace[1510633137] 'process raft request' (duration: 428.89383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:11.403779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:10.974648Z","time spent":"429.083932ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T11:12:11.431297Z","caller":"traceutil/trace.go:171","msg":"trace[2091240722] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"272.909189ms","start":"2026-02-05T11:12:11.158376Z","end":"2026-02-05T11:12:11.431285Z","steps":["trace[2091240722] 'process raft request' (duration: 272.492626ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:11.994713Z","caller":"traceutil/trace.go:171","msg":"trace[597828201] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"232.160107ms","start":"2026-02-05T11:12:11.762541Z","end":"2026-02-05T11:12:11.994701Z","steps":["trace[597828201] 'process raft request' (duration: 226.5538ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:16.732Z","caller":"traceutil/trace.go:171","msg":"trace[1562750681] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"126.19659ms","start":"2026-02-05T11:12:16.605788Z","end":"2026-02-05T11:12:16.731985Z","steps":["trace[1562750681] 'read index received' (duration: 126.09463ms)","trace[1562750681] 'applied index is now lower than readState.Index' (duration: 101.32µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:16.732077Z","caller":"traceutil/trace.go:171","msg":"trace[714843504] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"692.96323ms","start":"2026-02-05T11:12:16.039107Z","end":"2026-02-05T11:12:16.732071Z","steps":["trace[714843504] 'process raft request' (duration: 692.786069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:16.732143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:16.039079Z","time spent":"693.01299ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T11:12:16.732372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.582583ms","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-02-05T11:12:16.732395Z","caller":"traceutil/trace.go:171","msg":"trace[882257168] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"126.621633ms","start":"2026-02-05T11:12:16.605763Z","end":"2026-02-05T11:12:16.732384Z","steps":["trace[882257168] 'agreement among raft nodes before linearized reading' (duration: 126.556662ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:16.854958Z","caller":"traceutil/trace.go:171","msg":"trace[1805794285] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"119.093432ms","start":"2026-02-05T11:12:16.735842Z","end":"2026-02-05T11:12:16.854935Z","steps":["trace[1805794285] 'process raft request' (duration: 118.964301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:19.202133Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363757992710607704,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T11:12:19.381719Z","caller":"traceutil/trace.go:171","msg":"trace[2047479408] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"680.26072ms","start":"2026-02-05T11:12:18.701435Z","end":"2026-02-05T11:12:19.381696Z","steps":["trace[2047479408] 'read index received' (duration: 680.125339ms)","trace[2047479408] 'applied index is now lower than readState.Index' (duration: 134.491µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:19.381845Z","caller":"traceutil/trace.go:171","msg":"trace[817992734] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"680.585052ms","start":"2026-02-05T11:12:18.701248Z","end":"2026-02-05T11:12:19.381833Z","steps":["trace[817992734] 'process raft request' (duration: 680.31989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:19.381931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:18.701187Z","time spent":"680.678634ms","remote":"127.0.0.1:46136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T11:12:19.382071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.632603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:12:19.382096Z","caller":"traceutil/trace.go:171","msg":"trace[1723404135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"680.658733ms","start":"2026-02-05T11:12:18.701426Z","end":"2026-02-05T11:12:19.382085Z","steps":["trace[1723404135] 'agreement among raft nodes before linearized reading' (duration: 680.613023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:19.382116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:18.701422Z","time spent":"680.690554ms","remote":"127.0.0.1:45944","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-02-05T11:12:19.382326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.016155ms","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-02-05T11:12:19.382348Z","caller":"traceutil/trace.go:171","msg":"trace[571659925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:624; }","duration":"645.034735ms","start":"2026-02-05T11:12:18.737304Z","end":"2026-02-05T11:12:19.382339Z","steps":["trace[571659925] 'agreement among raft nodes before linearized reading' (duration: 644.936174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:19.382365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:18.737268Z","time spent":"645.092456ms","remote":"127.0.0.1:46202","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-02-05T11:12:19.382456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.203111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-05T11:12:19.38251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.256308ms","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-02-05T11:12:19.382527Z","caller":"traceutil/trace.go:171","msg":"trace[862579550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"484.321672ms","start":"2026-02-05T11:12:18.898187Z","end":"2026-02-05T11:12:19.382509Z","steps":["trace[862579550] 'agreement among raft nodes before linearized reading' (duration: 484.128641ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:19.382535Z","caller":"traceutil/trace.go:171","msg":"trace[839154993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"513.297928ms","start":"2026-02-05T11:12:18.86923Z","end":"2026-02-05T11:12:19.382528Z","steps":["trace[839154993] 'agreement among raft nodes before linearized reading' (duration: 513.218038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:19.38256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:18.869219Z","time spent":"513.336468ms","remote":"127.0.0.1:46202","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-02-05T11:12:19.382565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:18.898167Z","time spent":"484.391542ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T11:12:19.382671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.928733ms","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-02-05T11:12:19.382689Z","caller":"traceutil/trace.go:171","msg":"trace[2086074361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"524.947003ms","start":"2026-02-05T11:12:18.857737Z","end":"2026-02-05T11:12:19.382684Z","steps":["trace[2086074361] 'agreement among raft nodes before linearized reading' (duration: 524.891443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:19.382706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:18.857701Z","time spent":"525.001783ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-05T11:12:19.707726Z","caller":"traceutil/trace.go:171","msg":"trace[1198110738] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"314.585423ms","start":"2026-02-05T11:12:19.393122Z","end":"2026-02-05T11:12:19.707707Z","steps":["trace[1198110738] 'process raft request' (duration: 293.040151ms)","trace[1198110738] 'compare' (duration: 21.323629ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:19.707814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:19.393096Z","time spent":"314.668193ms","remote":"127.0.0.1:46202","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-02-05T11:12:19.708018Z","caller":"traceutil/trace.go:171","msg":"trace[1128751036] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"307.101936ms","start":"2026-02-05T11:12:19.400912Z","end":"2026-02-05T11:12:19.708014Z","steps":["trace[1128751036] 'process raft request' (duration: 306.669921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:19.708042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:19.400903Z","time spent":"307.124676ms","remote":"127.0.0.1:46202","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-02-05T11:12:19.707995Z","caller":"traceutil/trace.go:171","msg":"trace[23680252] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"306.38956ms","start":"2026-02-05T11:12:19.4016Z","end":"2026-02-05T11:12:19.70799Z","steps":["trace[23680252] 'process raft request' (duration: 306.36124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:19.708213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:19.401594Z","time spent":"306.604452ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T11:12:21.195667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363757992710607712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T11:12:21.696345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363757992710607712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T11:12:22.196711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363757992710607712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T11:12:22.696056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000581348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-05T11:12:22.696106Z","caller":"traceutil/trace.go:171","msg":"trace[2020776187] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000672602s","start":"2026-02-05T11:12:20.695424Z","end":"2026-02-05T11:12:22.696096Z","steps":["trace[2020776187] 'agreement among raft nodes before linearized reading' (duration: 2.000604849s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:22.696134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:20.695408Z","time spent":"2.000720845s","remote":"127.0.0.1:45942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T11:12:22.697628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363757992710607712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T11:12:23.073166Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.142266481s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-05T11:12:23.341616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.522756291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T11:12:23.341696Z","caller":"traceutil/trace.go:171","msg":"trace[564155939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"3.522869217s","start":"2026-02-05T11:12:19.818806Z","end":"2026-02-05T11:12:23.341675Z","steps":["trace[564155939] 'range keys from in-memory index tree' (duration: 3.522548734s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:23.341754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:19.818793Z","time spent":"3.52294976s","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T11:12:23.343588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.264941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T11:12:23.34365Z","caller":"traceutil/trace.go:171","msg":"trace[18964643] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"2.64818527s","start":"2026-02-05T11:12:20.695454Z","end":"2026-02-05T11:12:23.34364Z","steps":["trace[18964643] 'read index received' (duration: 2.377789093s)","trace[18964643] 'applied index is now lower than readState.Index' (duration: 270.395337ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:23.34385Z","caller":"traceutil/trace.go:171","msg":"trace[1783175908] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"3.413005862s","start":"2026-02-05T11:12:19.930832Z","end":"2026-02-05T11:12:23.343838Z","steps":["trace[1783175908] 'process raft request' (duration: 3.142433847s)","trace[1783175908] 'compare' (duration: 269.930515ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:23.343915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:19.930817Z","time spent":"3.413066255s","remote":"127.0.0.1:46202","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-02-05T11:12:23.344145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.929559789s","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-02-05T11:12:23.344183Z","caller":"traceutil/trace.go:171","msg":"trace[1543178757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"1.929614012s","start":"2026-02-05T11:12:21.41456Z","end":"2026-02-05T11:12:23.344174Z","steps":["trace[1543178757] 'agreement among raft nodes before linearized reading' (duration: 1.929520518s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:23.344229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:21.414547Z","time spent":"1.929672514s","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T11:12:23.344442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.866023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:12:23.344477Z","caller":"traceutil/trace.go:171","msg":"trace[422631217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"647.916886ms","start":"2026-02-05T11:12:22.696552Z","end":"2026-02-05T11:12:23.344469Z","steps":["trace[422631217] 'agreement among raft nodes before linearized reading' (duration: 647.866913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:23.344508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:22.696543Z","time spent":"647.959668ms","remote":"127.0.0.1:45944","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-02-05T11:12:23.344675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.629902475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T11:12:23.344709Z","caller":"traceutil/trace.go:171","msg":"trace[1581184563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"1.629944277s","start":"2026-02-05T11:12:21.714756Z","end":"2026-02-05T11:12:23.344701Z","steps":["trace[1581184563] 'agreement among raft nodes before linearized reading' (duration: 1.629864623s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:23.354588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:21.714751Z","time spent":"1.639614322s","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T11:12:23.354754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.641386766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T11:12:23.354866Z","caller":"traceutil/trace.go:171","msg":"trace[674849385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:629; }","duration":"1.641491721s","start":"2026-02-05T11:12:21.713352Z","end":"2026-02-05T11:12:23.354843Z","steps":["trace[674849385] 'agreement among raft nodes before linearized reading' (duration: 1.641302942s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:23.354935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:21.713347Z","time spent":"1.641581076s","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T11:12:23.355057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.642091649s","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-02-05T11:12:23.355123Z","caller":"traceutil/trace.go:171","msg":"trace[1138281592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"1.642186504s","start":"2026-02-05T11:12:21.712931Z","end":"2026-02-05T11:12:23.355117Z","steps":["trace[1138281592] 'agreement among raft nodes before linearized reading' (duration: 1.64208433s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:23.355177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:21.712918Z","time spent":"1.642253207s","remote":"127.0.0.1:46202","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-02-05T11:12:23.677633Z","caller":"traceutil/trace.go:171","msg":"trace[2023654216] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"316.028341ms","start":"2026-02-05T11:12:23.361579Z","end":"2026-02-05T11:12:23.677607Z","steps":["trace[2023654216] 'process raft request' (duration: 237.731071ms)","trace[2023654216] 'compare' (duration: 77.940073ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:23.677785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:23.361566Z","time spent":"316.130346ms","remote":"127.0.0.1:46030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T11:12:23.678181Z","caller":"traceutil/trace.go:171","msg":"trace[1890584335] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"308.56155ms","start":"2026-02-05T11:12:23.369608Z","end":"2026-02-05T11:12:23.678169Z","steps":["trace[1890584335] 'process raft request' (duration: 307.996983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:23.678279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:23.369491Z","time spent":"308.739838ms","remote":"127.0.0.1:46202","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-02-05T11:12:23.67843Z","caller":"traceutil/trace.go:171","msg":"trace[729379266] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"308.851813ms","start":"2026-02-05T11:12:23.369566Z","end":"2026-02-05T11:12:23.678418Z","steps":["trace[729379266] 'process raft request' (duration: 307.974782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:23.678487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:23.369554Z","time spent":"308.905226ms","remote":"127.0.0.1:46202","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-02-05T11:12:23.678785Z","caller":"traceutil/trace.go:171","msg":"trace[476196598] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"304.94911ms","start":"2026-02-05T11:12:23.373826Z","end":"2026-02-05T11:12:23.678775Z","steps":["trace[476196598] 'process raft request' (duration: 303.817577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:23.678846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:23.373818Z","time spent":"304.999133ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T11:12:23.678972Z","caller":"traceutil/trace.go:171","msg":"trace[1897854641] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"307.991874ms","start":"2026-02-05T11:12:23.370969Z","end":"2026-02-05T11:12:23.678961Z","steps":["trace[1897854641] 'process raft request' (duration: 306.660152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:23.679024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:23.370961Z","time spent":"308.038725ms","remote":"127.0.0.1:46202","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-02-05T11:12:23.679283Z","caller":"traceutil/trace.go:171","msg":"trace[1800570836] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"315.677284ms","start":"2026-02-05T11:12:23.363587Z","end":"2026-02-05T11:12:23.679264Z","steps":["trace[1800570836] 'read index received' (duration: 27.496693ms)","trace[1800570836] 'applied index is now lower than readState.Index' (duration: 288.179541ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:23.688712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.093286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:12:23.688799Z","caller":"traceutil/trace.go:171","msg":"trace[808469109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"325.216312ms","start":"2026-02-05T11:12:23.363565Z","end":"2026-02-05T11:12:23.688782Z","steps":["trace[808469109] 'agreement among raft nodes before linearized reading' (duration: 315.749897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:23.688828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:23.363546Z","time spent":"325.275325ms","remote":"127.0.0.1:45942","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-02-05T11:12:25.830833Z","caller":"traceutil/trace.go:171","msg":"trace[1877015509] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"132.332847ms","start":"2026-02-05T11:12:25.698438Z","end":"2026-02-05T11:12:25.830771Z","steps":["trace[1877015509] 'process raft request' (duration: 132.218152ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:25.837122Z","caller":"traceutil/trace.go:171","msg":"trace[1470384797] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:662; }","duration":"130.184449ms","start":"2026-02-05T11:12:25.706921Z","end":"2026-02-05T11:12:25.837105Z","steps":["trace[1470384797] 'read index received' (duration: 123.819987ms)","trace[1470384797] 'applied index is now lower than readState.Index' (duration: 6.363832ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:25.837296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.357567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5w2t5\" ","response":"range_response_count:1 size:17078"} {"level":"info","ts":"2026-02-05T11:12:25.837318Z","caller":"traceutil/trace.go:171","msg":"trace[1579075663] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5w2t5; range_end:; response_count:1; response_revision:646; }","duration":"130.41039ms","start":"2026-02-05T11:12:25.706901Z","end":"2026-02-05T11:12:25.837311Z","steps":["trace[1579075663] 'agreement among raft nodes before linearized reading' (duration: 130.265773ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:25.837536Z","caller":"traceutil/trace.go:171","msg":"trace[1254932433] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"137.332576ms","start":"2026-02-05T11:12:25.700196Z","end":"2026-02-05T11:12:25.837529Z","steps":["trace[1254932433] 'process raft request' (duration: 136.74138ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:25.837627Z","caller":"traceutil/trace.go:171","msg":"trace[1190903581] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"137.385359ms","start":"2026-02-05T11:12:25.700236Z","end":"2026-02-05T11:12:25.837621Z","steps":["trace[1190903581] 'process raft request' (duration: 136.785861ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:25.837724Z","caller":"traceutil/trace.go:171","msg":"trace[1372398788] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"130.619479ms","start":"2026-02-05T11:12:25.707099Z","end":"2026-02-05T11:12:25.837718Z","steps":["trace[1372398788] 'process raft request' (duration: 129.949149ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:25.837808Z","caller":"traceutil/trace.go:171","msg":"trace[1751892692] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"129.735548ms","start":"2026-02-05T11:12:25.708067Z","end":"2026-02-05T11:12:25.837802Z","steps":["trace[1751892692] 'process raft request' (duration: 129.016055ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:30.638662Z","caller":"traceutil/trace.go:171","msg":"trace[132273200] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"116.447377ms","start":"2026-02-05T11:12:30.5222Z","end":"2026-02-05T11:12:30.638647Z","steps":["trace[132273200] 'process raft request' (duration: 107.495791ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:30.781871Z","caller":"traceutil/trace.go:171","msg":"trace[311081125] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"123.920298ms","start":"2026-02-05T11:12:30.657921Z","end":"2026-02-05T11:12:30.781841Z","steps":["trace[311081125] 'process raft request' (duration: 85.079581ms)","trace[311081125] 'compare' (duration: 38.637948ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:30.921861Z","caller":"traceutil/trace.go:171","msg":"trace[967523746] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"120.958341ms","start":"2026-02-05T11:12:30.800881Z","end":"2026-02-05T11:12:30.921839Z","steps":["trace[967523746] 'process raft request' (duration: 119.33174ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:36.974249Z","caller":"traceutil/trace.go:171","msg":"trace[1001269758] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"199.208837ms","start":"2026-02-05T11:12:36.775018Z","end":"2026-02-05T11:12:36.974227Z","steps":["trace[1001269758] 'process raft request' (duration: 199.098732ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.133348Z","caller":"traceutil/trace.go:171","msg":"trace[2101196401] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"151.606243ms","start":"2026-02-05T11:12:36.981736Z","end":"2026-02-05T11:12:37.133342Z","steps":["trace[2101196401] 'process raft request' (duration: 151.312772ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.133299Z","caller":"traceutil/trace.go:171","msg":"trace[766767649] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"150.150085ms","start":"2026-02-05T11:12:36.983135Z","end":"2026-02-05T11:12:37.133285Z","steps":["trace[766767649] 'read index received' (duration: 149.958337ms)","trace[766767649] 'applied index is now lower than readState.Index' (duration: 191.008µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:37.134655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.507419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-nj7wb\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-05T11:12:37.134685Z","caller":"traceutil/trace.go:171","msg":"trace[191361389] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-nj7wb; range_end:; response_count:1; response_revision:736; }","duration":"151.558692ms","start":"2026-02-05T11:12:36.983119Z","end":"2026-02-05T11:12:37.134677Z","steps":["trace[191361389] 'agreement among raft nodes before linearized reading' (duration: 151.338612ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.176665Z","caller":"traceutil/trace.go:171","msg":"trace[879707078] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"193.420015ms","start":"2026-02-05T11:12:36.983227Z","end":"2026-02-05T11:12:37.176647Z","steps":["trace[879707078] 'process raft request' (duration: 193.260638ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.34586Z","caller":"traceutil/trace.go:171","msg":"trace[1521010177] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"152.989598ms","start":"2026-02-05T11:12:37.192855Z","end":"2026-02-05T11:12:37.345845Z","steps":["trace[1521010177] 'read index received' (duration: 152.859343ms)","trace[1521010177] 'applied index is now lower than readState.Index' (duration: 129.725µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:37.345976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.102243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T11:12:37.345994Z","caller":"traceutil/trace.go:171","msg":"trace[1911116787] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:738; }","duration":"153.158135ms","start":"2026-02-05T11:12:37.192829Z","end":"2026-02-05T11:12:37.345987Z","steps":["trace[1911116787] 'agreement among raft nodes before linearized reading' (duration: 153.074572ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.346213Z","caller":"traceutil/trace.go:171","msg":"trace[1364080784] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"204.581907ms","start":"2026-02-05T11:12:37.141624Z","end":"2026-02-05T11:12:37.346206Z","steps":["trace[1364080784] 'process raft request' (duration: 204.13987ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.600235Z","caller":"traceutil/trace.go:171","msg":"trace[1922954475] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"238.069007ms","start":"2026-02-05T11:12:37.362145Z","end":"2026-02-05T11:12:37.600213Z","steps":["trace[1922954475] 'process raft request' (duration: 238.028486ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.601383Z","caller":"traceutil/trace.go:171","msg":"trace[512388197] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"245.019563ms","start":"2026-02-05T11:12:37.356349Z","end":"2026-02-05T11:12:37.601368Z","steps":["trace[512388197] 'process raft request' (duration: 203.273545ms)","trace[512388197] 'compare' (duration: 40.377664ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:37.601875Z","caller":"traceutil/trace.go:171","msg":"trace[25849304] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"244.971631ms","start":"2026-02-05T11:12:37.356892Z","end":"2026-02-05T11:12:37.601863Z","steps":["trace[25849304] 'process raft request' (duration: 243.203712ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.60314Z","caller":"traceutil/trace.go:171","msg":"trace[1714907120] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"241.386229ms","start":"2026-02-05T11:12:37.361744Z","end":"2026-02-05T11:12:37.60313Z","steps":["trace[1714907120] 'process raft request' (duration: 238.39331ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.66676Z","caller":"traceutil/trace.go:171","msg":"trace[1472786702] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:763; }","duration":"144.20941ms","start":"2026-02-05T11:12:37.522525Z","end":"2026-02-05T11:12:37.666735Z","steps":["trace[1472786702] 'read index received' (duration: 37.149246ms)","trace[1472786702] 'applied index is now lower than readState.Index' (duration: 107.059274ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:37.66778Z","caller":"traceutil/trace.go:171","msg":"trace[1322373902] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"225.458199ms","start":"2026-02-05T11:12:37.442314Z","end":"2026-02-05T11:12:37.667773Z","steps":["trace[1322373902] 'process raft request' (duration: 224.126425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:37.668101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.565854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T11:12:37.668128Z","caller":"traceutil/trace.go:171","msg":"trace[7774185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:744; }","duration":"145.631587ms","start":"2026-02-05T11:12:37.52249Z","end":"2026-02-05T11:12:37.668122Z","steps":["trace[7774185] 'agreement among raft nodes before linearized reading' (duration: 145.47547ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.849409Z","caller":"traceutil/trace.go:171","msg":"trace[586275664] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"230.324171ms","start":"2026-02-05T11:12:37.61907Z","end":"2026-02-05T11:12:37.849394Z","steps":["trace[586275664] 'process raft request' (duration: 230.29825ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.849705Z","caller":"traceutil/trace.go:171","msg":"trace[398709225] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"231.704865ms","start":"2026-02-05T11:12:37.617995Z","end":"2026-02-05T11:12:37.8497Z","steps":["trace[398709225] 'process raft request' (duration: 231.213435ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.849819Z","caller":"traceutil/trace.go:171","msg":"trace[574523426] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"231.662633ms","start":"2026-02-05T11:12:37.618152Z","end":"2026-02-05T11:12:37.849815Z","steps":["trace[574523426] 'process raft request' (duration: 231.190465ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.850092Z","caller":"traceutil/trace.go:171","msg":"trace[1643363045] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:771; }","duration":"183.27392ms","start":"2026-02-05T11:12:37.666804Z","end":"2026-02-05T11:12:37.850078Z","steps":["trace[1643363045] 'read index received' (duration: 183.271399ms)","trace[1643363045] 'applied index is now lower than readState.Index' (duration: 2.171µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:37.850179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.543996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.249\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T11:12:37.850194Z","caller":"traceutil/trace.go:171","msg":"trace[1286845547] range","detail":"{range_begin:/registry/masterleases/199.19.213.249; range_end:; response_count:1; response_revision:747; }","duration":"315.587728ms","start":"2026-02-05T11:12:37.534601Z","end":"2026-02-05T11:12:37.850189Z","steps":["trace[1286845547] 'agreement among raft nodes before linearized reading' (duration: 315.517815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:37.850215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:37.534585Z","time spent":"315.619069ms","remote":"127.0.0.1:45984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.249\" "} {"level":"info","ts":"2026-02-05T11:12:37.939013Z","caller":"traceutil/trace.go:171","msg":"trace[1368668935] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"259.499628ms","start":"2026-02-05T11:12:37.679487Z","end":"2026-02-05T11:12:37.938987Z","steps":["trace[1368668935] 'process raft request' (duration: 259.264829ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:37.939208Z","caller":"traceutil/trace.go:171","msg":"trace[2145598672] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"224.262679ms","start":"2026-02-05T11:12:37.714939Z","end":"2026-02-05T11:12:37.939202Z","steps":["trace[2145598672] 'process raft request' (duration: 223.895255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:37.939354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.065695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:12:37.939406Z","caller":"traceutil/trace.go:171","msg":"trace[223601483] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:749; }","duration":"212.140028ms","start":"2026-02-05T11:12:37.727248Z","end":"2026-02-05T11:12:37.939388Z","steps":["trace[223601483] 'agreement among raft nodes before linearized reading' (duration: 212.063525ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:38.126288Z","caller":"traceutil/trace.go:171","msg":"trace[884818963] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"269.645666ms","start":"2026-02-05T11:12:37.856629Z","end":"2026-02-05T11:12:38.126275Z","steps":["trace[884818963] 'process raft request' (duration: 269.566223ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:38.12674Z","caller":"traceutil/trace.go:171","msg":"trace[1209411879] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:775; }","duration":"187.866157ms","start":"2026-02-05T11:12:37.938868Z","end":"2026-02-05T11:12:38.126734Z","steps":["trace[1209411879] 'read index received' (duration: 187.863827ms)","trace[1209411879] 'applied index is now lower than readState.Index' (duration: 2.01µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:38.126834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.02654ms","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-02-05T11:12:38.126849Z","caller":"traceutil/trace.go:171","msg":"trace[1055343223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"204.07169ms","start":"2026-02-05T11:12:37.922773Z","end":"2026-02-05T11:12:38.126845Z","steps":["trace[1055343223] 'agreement among raft nodes before linearized reading' (duration: 204.006238ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:38.202354Z","caller":"traceutil/trace.go:171","msg":"trace[557620113] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"154.234116ms","start":"2026-02-05T11:12:38.048105Z","end":"2026-02-05T11:12:38.202339Z","steps":["trace[557620113] 'process raft request' (duration: 154.161673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:38.202927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.335933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T11:12:38.202947Z","caller":"traceutil/trace.go:171","msg":"trace[461178669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"256.383264ms","start":"2026-02-05T11:12:37.946557Z","end":"2026-02-05T11:12:38.202941Z","steps":["trace[461178669] 'agreement among raft nodes before linearized reading' (duration: 256.303751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:38.202993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.084313ms","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-02-05T11:12:38.203063Z","caller":"traceutil/trace.go:171","msg":"trace[926993967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"254.175986ms","start":"2026-02-05T11:12:37.948873Z","end":"2026-02-05T11:12:38.203049Z","steps":["trace[926993967] 'agreement among raft nodes before linearized reading' (duration: 253.941567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:38.20312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.008755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T11:12:38.203135Z","caller":"traceutil/trace.go:171","msg":"trace[1321544104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:751; }","duration":"234.043987ms","start":"2026-02-05T11:12:37.969087Z","end":"2026-02-05T11:12:38.203131Z","steps":["trace[1321544104] 'agreement among raft nodes before linearized reading' (duration: 234.000825ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:38.40744Z","caller":"traceutil/trace.go:171","msg":"trace[1515576191] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:776; }","duration":"204.727729ms","start":"2026-02-05T11:12:38.202692Z","end":"2026-02-05T11:12:38.40742Z","steps":["trace[1515576191] 'read index received' (duration: 204.089475ms)","trace[1515576191] 'applied index is now lower than readState.Index' (duration: 637.254µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:38.407544Z","caller":"traceutil/trace.go:171","msg":"trace[1919146940] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"278.997496ms","start":"2026-02-05T11:12:38.128537Z","end":"2026-02-05T11:12:38.407535Z","steps":["trace[1919146940] 'process raft request' (duration: 278.122182ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:38.407947Z","caller":"traceutil/trace.go:171","msg":"trace[693416653] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"275.894092ms","start":"2026-02-05T11:12:38.132041Z","end":"2026-02-05T11:12:38.407935Z","steps":["trace[693416653] 'process raft request' (duration: 275.180155ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:38.408174Z","caller":"traceutil/trace.go:171","msg":"trace[706019512] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"275.722556ms","start":"2026-02-05T11:12:38.13244Z","end":"2026-02-05T11:12:38.408163Z","steps":["trace[706019512] 'process raft request' (duration: 274.888004ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:38.408303Z","caller":"traceutil/trace.go:171","msg":"trace[1023782640] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"275.83593ms","start":"2026-02-05T11:12:38.132459Z","end":"2026-02-05T11:12:38.408294Z","steps":["trace[1023782640] 'process raft request' (duration: 274.909284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:38.408582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.265518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-nj7wb\" ","response":"range_response_count:1 size:4812"} {"level":"info","ts":"2026-02-05T11:12:38.408621Z","caller":"traceutil/trace.go:171","msg":"trace[637807821] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-nj7wb; range_end:; response_count:1; response_revision:755; }","duration":"276.344001ms","start":"2026-02-05T11:12:38.132266Z","end":"2026-02-05T11:12:38.40861Z","steps":["trace[637807821] 'agreement among raft nodes before linearized reading' (duration: 276.195385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:38.803595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.092088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T11:12:38.80376Z","caller":"traceutil/trace.go:171","msg":"trace[1736109807] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:780; }","duration":"396.245438ms","start":"2026-02-05T11:12:38.407502Z","end":"2026-02-05T11:12:38.803748Z","steps":["trace[1736109807] 'read index received' (duration: 253.901339ms)","trace[1736109807] 'applied index is now lower than readState.Index' (duration: 142.343429ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:12:38.803892Z","caller":"traceutil/trace.go:171","msg":"trace[201588350] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"598.103814ms","start":"2026-02-05T11:12:38.205781Z","end":"2026-02-05T11:12:38.803885Z","steps":["trace[201588350] 'process raft request' (duration: 455.657322ms)","trace[201588350] 'compare' (duration: 141.897321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:12:38.803934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:38.205742Z","time spent":"598.167707ms","remote":"127.0.0.1:46202","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-02-05T11:12:38.804033Z","caller":"traceutil/trace.go:171","msg":"trace[1552197519] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"596.785493ms","start":"2026-02-05T11:12:38.207242Z","end":"2026-02-05T11:12:38.804027Z","steps":["trace[1552197519] 'process raft request' (duration: 596.430139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:38.80406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:38.207234Z","time spent":"596.812805ms","remote":"127.0.0.1:46202","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-02-05T11:12:38.804122Z","caller":"traceutil/trace.go:171","msg":"trace[275710358] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"595.71944ms","start":"2026-02-05T11:12:38.208398Z","end":"2026-02-05T11:12:38.804117Z","steps":["trace[275710358] 'process raft request' (duration: 595.312505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:38.804148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:38.208392Z","time spent":"595.742672ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T11:12:38.804231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.336997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T11:12:38.804259Z","caller":"traceutil/trace.go:171","msg":"trace[1108114921] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:758; }","duration":"581.398128ms","start":"2026-02-05T11:12:38.222844Z","end":"2026-02-05T11:12:38.804242Z","steps":["trace[1108114921] 'agreement among raft nodes before linearized reading' (duration: 581.359637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:38.804274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:38.222824Z","time spent":"581.446541ms","remote":"127.0.0.1:46260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-05T11:12:38.819118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.702593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-05T11:12:38.819171Z","caller":"traceutil/trace.go:171","msg":"trace[1060073276] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:760; }","duration":"402.782366ms","start":"2026-02-05T11:12:38.416378Z","end":"2026-02-05T11:12:38.819161Z","steps":["trace[1060073276] 'agreement among raft nodes before linearized reading' (duration: 402.683262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:38.819198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:38.416371Z","time spent":"402.822887ms","remote":"127.0.0.1:46130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-05T11:12:38.819526Z","caller":"traceutil/trace.go:171","msg":"trace[13679162] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"403.216963ms","start":"2026-02-05T11:12:38.416304Z","end":"2026-02-05T11:12:38.819521Z","steps":["trace[13679162] 'process raft request' (duration: 402.651721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:38.819573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:38.416289Z","time spent":"403.251145ms","remote":"127.0.0.1:46136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4809,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T11:12:38.819661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.743587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-05T11:12:38.819677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.401711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T11:12:38.822135Z","caller":"traceutil/trace.go:171","msg":"trace[1248273481] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:760; }","duration":"185.900219ms","start":"2026-02-05T11:12:38.636226Z","end":"2026-02-05T11:12:38.822126Z","steps":["trace[1248273481] 'agreement among raft nodes before linearized reading' (duration: 183.419802ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:12:38.819857Z","caller":"traceutil/trace.go:171","msg":"trace[1281006910] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"403.464703ms","start":"2026-02-05T11:12:38.416387Z","end":"2026-02-05T11:12:38.819852Z","steps":["trace[1281006910] 'process raft request' (duration: 402.643881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:12:38.822326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:12:38.416369Z","time spent":"405.927119ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T11:12:38.822104Z","caller":"traceutil/trace.go:171","msg":"trace[1605637793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"129.205604ms","start":"2026-02-05T11:12:38.69288Z","end":"2026-02-05T11:12:38.822086Z","steps":["trace[1605637793] 'agreement among raft nodes before linearized reading' (duration: 126.730956ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:13:01.107184Z","caller":"traceutil/trace.go:171","msg":"trace[170490191] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"136.689254ms","start":"2026-02-05T11:13:00.970478Z","end":"2026-02-05T11:13:01.107167Z","steps":["trace[170490191] 'process raft request' (duration: 101.09524ms)","trace[170490191] 'compare' (duration: 35.503311ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:13:38.978022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.411037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:13:38.97811Z","caller":"traceutil/trace.go:171","msg":"trace[1043869641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"284.513369ms","start":"2026-02-05T11:13:38.693572Z","end":"2026-02-05T11:13:38.978085Z","steps":["trace[1043869641] 'range keys from in-memory index tree' (duration: 284.382396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:13:38.978295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.927726ms","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-02-05T11:13:38.978681Z","caller":"traceutil/trace.go:171","msg":"trace[1078498406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:985; }","duration":"289.417896ms","start":"2026-02-05T11:13:38.689171Z","end":"2026-02-05T11:13:38.978589Z","steps":["trace[1078498406] 'range keys from in-memory index tree' (duration: 288.583398ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:13:47.003654Z","caller":"traceutil/trace.go:171","msg":"trace[489196074] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"311.888102ms","start":"2026-02-05T11:13:46.69174Z","end":"2026-02-05T11:13:47.003629Z","steps":["trace[489196074] 'read index received' (duration: 311.715038ms)","trace[489196074] 'applied index is now lower than readState.Index' (duration: 171.784µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T11:13:47.00379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.026565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T11:13:47.003852Z","caller":"traceutil/trace.go:171","msg":"trace[1677793774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"312.135717ms","start":"2026-02-05T11:13:46.691702Z","end":"2026-02-05T11:13:47.003838Z","steps":["trace[1677793774] 'agreement among raft nodes before linearized reading' (duration: 312.032235ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:13:47.003852Z","caller":"traceutil/trace.go:171","msg":"trace[48581008] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"572.450347ms","start":"2026-02-05T11:13:46.431376Z","end":"2026-02-05T11:13:47.003827Z","steps":["trace[48581008] 'process raft request' (duration: 572.113161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:13:47.003901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:13:46.691683Z","time spent":"312.201899ms","remote":"127.0.0.1:45942","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-02-05T11:13:47.003965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T11:13:46.431359Z","time spent":"572.53526ms","remote":"127.0.0.1:46202","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-02-05T11:13:49.280052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.337532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T11:13:49.28026Z","caller":"traceutil/trace.go:171","msg":"trace[529954318] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"184.777859ms","start":"2026-02-05T11:13:49.095463Z","end":"2026-02-05T11:13:49.280241Z","steps":["trace[529954318] 'process raft request' (duration: 83.162312ms)","trace[529954318] 'compare' (duration: 101.168188ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T11:14:19.591072Z","caller":"traceutil/trace.go:171","msg":"trace[1069528092] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"151.298898ms","start":"2026-02-05T11:14:19.439737Z","end":"2026-02-05T11:14:19.591036Z","steps":["trace[1069528092] 'process raft request' (duration: 148.785657ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T11:14:19.59434Z","caller":"traceutil/trace.go:171","msg":"trace[1545346767] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"154.574871ms","start":"2026-02-05T11:14:19.439739Z","end":"2026-02-05T11:14:19.594314Z","steps":["trace[1545346767] 'process raft request' (duration: 154.278406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T11:14:20.309979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.928425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T11:14:20.310061Z","caller":"traceutil/trace.go:171","msg":"trace[962547238] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1114; }","duration":"290.22223ms","start":"2026-02-05T11:14:20.019822Z","end":"2026-02-05T11:14:20.310044Z","steps":["trace[962547238] 'count revisions from in-memory index tree' (duration: 289.822334ms)"],"step_count":1}